builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1481390044.31 results: success (0) buildid: 20161210082451 builduid: 6f1ee99af11d407eb8331a3558ac0fc4 revision: 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.308906) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.309718) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.310304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.359591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.360208) ========= 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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-10 09:14:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-12-10 09:14:04 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.463226) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.463724) ========= 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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021741 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.514510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:04.514830) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 --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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-10 09:14:04,737 truncating revision to first 12 chars 2016-12-10 09:14:04,737 Setting DEBUG logging. 2016-12-10 09:14:04,737 attempt 1/10 2016-12-10 09:14:04,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5cc901aa30a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-10 09:14:05,160 unpacking tar archive at: mozilla-central-5cc901aa30a2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.728887 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:05.258562) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:05.258910) ========= script_repo_revision: 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:05.259336) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:05.259610) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 09:14:05.273751) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-10 09:14:05.274187) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:14:05 INFO - MultiFileLogger online at 20161210 09:14:05 in /builds/slave/test 09:14:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:14:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:14:05 INFO - {'append_to_log': False, 09:14:05 INFO - 'base_work_dir': '/builds/slave/test', 09:14:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:14:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:14:05 INFO - 'branch': 'Firefox-Non-PGO', 09:14:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:14:05 INFO - 'config_files': ('talos/linux_config.py',), 09:14:05 INFO - 'default_actions': ('clobber', 09:14:05 INFO - 'read-buildbot-config', 09:14:05 INFO - 'download-and-extract', 09:14:05 INFO - 'populate-webroot', 09:14:05 INFO - 'create-virtualenv', 09:14:05 INFO - 'install', 09:14:05 INFO - 'run-tests'), 09:14:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:14:05 INFO - 'download_minidump_stackwalk': True, 09:14:05 INFO - 'download_symbols': 'ondemand', 09:14:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:14:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:14:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:14:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:14:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:14:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:14:05 INFO - 'installer_path': 'installer.exe', 09:14:05 INFO - 'log_level': 'info', 09:14:05 INFO - 'log_name': 'talos', 09:14:05 INFO - 'log_to_console': True, 09:14:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:14:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:14:05 INFO - 'opt_config_files': (), 09:14:05 INFO - 'pip_index': False, 09:14:05 INFO - 'sps_profile': False, 09:14:05 INFO - 'sps_profile_interval': 0, 09:14:05 INFO - 'suite': 'dromaeojs', 09:14:05 INFO - 'system_bits': '32', 09:14:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:14:05 INFO - 'title': 'talos-linux64-ix-011', 09:14:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:14:05 INFO - 'use_talos_json': True, 09:14:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:14:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:14:05 INFO - 'work_dir': 'build'} 09:14:05 INFO - [mozharness: 2016-12-10 17:14:05.595319Z] Running clobber step. 09:14:05 INFO - Running pre-action listener: _resource_record_pre_action 09:14:05 INFO - Running main action method: clobber 09:14:05 INFO - rmtree: /builds/slave/test/build 09:14:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:14:06 INFO - Running post-action listener: _resource_record_post_action 09:14:06 INFO - [mozharness: 2016-12-10 17:14:06.045236Z] Finished clobber step (success) 09:14:06 INFO - [mozharness: 2016-12-10 17:14:06.045335Z] Running read-buildbot-config step. 09:14:06 INFO - Running pre-action listener: _resource_record_pre_action 09:14:06 INFO - Running main action method: read_buildbot_config 09:14:06 INFO - Using buildbot properties: 09:14:06 INFO - { 09:14:06 INFO - "project": "", 09:14:06 INFO - "product": "firefox", 09:14:06 INFO - "script_repo_revision": "production", 09:14:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:14:06 INFO - "repository": "", 09:14:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:14:06 INFO - "buildid": "20161210082451", 09:14:06 INFO - "pgo_build": "False", 09:14:06 INFO - "basedir": "/builds/slave/test", 09:14:06 INFO - "buildnumber": 740, 09:14:06 INFO - "slavename": "talos-linux64-ix-011", 09:14:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:14:06 INFO - "platform": "ubuntu64_hw", 09:14:06 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:14:06 INFO - "branch": "mozilla-central", 09:14:06 INFO - "repo_path": "mozilla-central", 09:14:06 INFO - "revision": "5cc901aa30a2f8ce528082f8b5a4f06c34fef363", 09:14:06 INFO - "stage_platform": "linux64", 09:14:06 INFO - "builduid": "6f1ee99af11d407eb8331a3558ac0fc4", 09:14:06 INFO - "slavebuilddir": "test" 09:14:06 INFO - } 09:14:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:14:06 INFO - Running post-action listener: _resource_record_post_action 09:14:06 INFO - [mozharness: 2016-12-10 17:14:06.047744Z] Finished read-buildbot-config step (success) 09:14:06 INFO - [mozharness: 2016-12-10 17:14:06.047832Z] Running download-and-extract step. 09:14:06 INFO - Running pre-action listener: _resource_record_pre_action 09:14:06 INFO - Running main action method: download_and_extract 09:14:06 INFO - mkdir: /builds/slave/test/build/tests 09:14:06 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')]} 09:14:06 INFO - trying https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/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 09:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/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 09:14:06 INFO - Downloaded 1361 bytes. 09:14:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:14:06 INFO - Using the following test package requirements: 09:14:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:14:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:14:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:14:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'jsshell-linux-x86_64.zip'], 09:14:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:14:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:14:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:14:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:14:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:14:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:14:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:14:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:14:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:14:06 INFO - Fetch https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:14:07 INFO - Expected file size: 20222408 09:14:07 INFO - Obtained file size: 20222408 09:14:11 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 09:14:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:14:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:14:11 INFO - Fetch https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:14:12 INFO - Expected file size: 20222408 09:14:12 INFO - Obtained file size: 20222408 09:14:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:14:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:14:16 INFO - Fetch https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:14:17 INFO - Expected file size: 14409734 09:14:17 INFO - Obtained file size: 14409734 09:14:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:17 INFO - trying https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/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 09:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/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 09:14:19 INFO - Downloaded 55769244 bytes. 09:14:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:14:19 INFO - mkdir: /builds/slave/test/properties 09:14:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:14:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:14:19 INFO - Contents: 09:14:19 INFO - build_url:https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:14:20 INFO - Running post-action listener: _resource_record_post_action 09:14:20 INFO - Running post-action listener: set_extra_try_arguments 09:14:20 INFO - [mozharness: 2016-12-10 17:14:20.116319Z] Finished download-and-extract step (success) 09:14:20 INFO - [mozharness: 2016-12-10 17:14:20.116543Z] Running populate-webroot step. 09:14:20 INFO - Running pre-action listener: _resource_record_pre_action 09:14:20 INFO - Running main action method: populate_webroot 09:14:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:14:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:14:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:14:20 INFO - '--tppagecycles', 09:14:20 INFO - '7'], 09:14:20 INFO - 'tests': ['tsvgm']}}, 09:14:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:14:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:14:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:14:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:14:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['damp', 'tps']}, 09:14:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'tests': ['damp', 'tps']}, 09:14:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['dromaeo_dom']}, 09:14:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:14:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['basic_compositor_video']}, 09:14:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:14:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['a11yr', 09:14:20 INFO - 'ts_paint', 09:14:20 INFO - 'tpaint', 09:14:20 INFO - 'sessionrestore', 09:14:20 INFO - 'sessionrestore_no_auto_restore', 09:14:20 INFO - 'tabpaint']}, 09:14:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['tsvgx', 09:14:20 INFO - 'tsvgr_opacity', 09:14:20 INFO - 'tart', 09:14:20 INFO - 'tscrollx', 09:14:20 INFO - 'cart', 09:14:20 INFO - 'tsvg_static']}, 09:14:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:14:20 INFO - 'tsvgr_opacity', 09:14:20 INFO - 'tart', 09:14:20 INFO - 'tscrollx', 09:14:20 INFO - 'cart', 09:14:20 INFO - 'tsvg_static']}, 09:14:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'talos_options': ['--disable-e10s'], 09:14:20 INFO - 'tests': ['tp5o']}, 09:14:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:14:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'talos_options': ['--disable-e10s', 09:14:20 INFO - '--xperf_path', 09:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:20 INFO - 'tests': ['tp5n']}, 09:14:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:14:20 INFO - 'talos_options': ['--xperf_path', 09:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:20 INFO - 'tests': ['tp5n']}}, 09:14:20 INFO - 'talos.zip': {'path': '', 09:14:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:14:20 INFO - Running post-action listener: _resource_record_post_action 09:14:20 INFO - [mozharness: 2016-12-10 17:14:20.134335Z] Finished populate-webroot step (success) 09:14:20 INFO - [mozharness: 2016-12-10 17:14:20.134423Z] Running create-virtualenv step. 09:14:20 INFO - Running pre-action listener: _resource_record_pre_action 09:14:20 INFO - Running main action method: create_virtualenv 09:14:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:14:20 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 09:14:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:14:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:14:20 INFO - Using real prefix '/usr' 09:14:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:14:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:14:22 INFO - Installing pip.................done. 09:14:22 INFO - Return code: 0 09:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:14:22 INFO - Reading from file tmpfile_stdout 09:14:22 INFO - Output received: 09:14:22 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) 09:14:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:22 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:22 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 09:14:22 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 09:14:22 INFO - Using env: {'DISPLAY': ':0', 09:14:22 INFO - 'HOME': '/home/cltbld', 09:14:22 INFO - 'LANG': 'en_US.UTF-8', 09:14:22 INFO - 'LANGUAGE': 'en_US:en', 09:14:22 INFO - 'LOGNAME': 'cltbld', 09:14:22 INFO - 'MAIL': '/var/mail/cltbld', 09:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:22 INFO - 'MOZ_NO_REMOTE': '1', 09:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:22 INFO - 'NO_EM_RESTART': '1', 09:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:22 INFO - 'PWD': '/builds/slave/test', 09:14:22 INFO - 'SHELL': '/bin/bash', 09:14:22 INFO - 'SHLVL': '1', 09:14:22 INFO - 'TERM': 'linux', 09:14:22 INFO - 'TMOUT': '86400', 09:14:22 INFO - 'USER': 'cltbld', 09:14:22 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:22 INFO - '_': '/tools/buildbot/bin/python'} 09:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:23 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 09:14:23 INFO - Cleaning up... 09:14:23 INFO - Return code: 0 09:14:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:14:23 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')]} 09:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:23 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')]} 09:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:23 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:23 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 09:14:23 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 09:14:23 INFO - Using env: {'DISPLAY': ':0', 09:14:23 INFO - 'HOME': '/home/cltbld', 09:14:23 INFO - 'LANG': 'en_US.UTF-8', 09:14:23 INFO - 'LANGUAGE': 'en_US:en', 09:14:23 INFO - 'LOGNAME': 'cltbld', 09:14:23 INFO - 'MAIL': '/var/mail/cltbld', 09:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:23 INFO - 'MOZ_NO_REMOTE': '1', 09:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:23 INFO - 'NO_EM_RESTART': '1', 09:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:23 INFO - 'PWD': '/builds/slave/test', 09:14:23 INFO - 'SHELL': '/bin/bash', 09:14:23 INFO - 'SHLVL': '1', 09:14:23 INFO - 'TERM': 'linux', 09:14:23 INFO - 'TMOUT': '86400', 09:14:23 INFO - 'USER': 'cltbld', 09:14:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:23 INFO - '_': '/tools/buildbot/bin/python'} 09:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:23 INFO - Downloading/unpacking psutil>=3.1.1 09:14:23 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 09:14:23 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 09:14:23 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 09:14:23 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 09:14:23 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 09:14:23 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 09:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:25 INFO - Installing collected packages: psutil 09:14:25 INFO - Running setup.py install for psutil 09:14:25 INFO - building 'psutil._psutil_linux' extension 09:14:25 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 09:14:25 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 09:14:25 INFO - building 'psutil._psutil_posix' extension 09:14:25 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 09:14:25 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 09:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:25 INFO - Successfully installed psutil 09:14:25 INFO - Cleaning up... 09:14:25 INFO - Return code: 0 09:14:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:14:25 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')]} 09:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:25 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')]} 09:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:25 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:25 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 09:14:25 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 09:14:25 INFO - Using env: {'DISPLAY': ':0', 09:14:25 INFO - 'HOME': '/home/cltbld', 09:14:25 INFO - 'LANG': 'en_US.UTF-8', 09:14:25 INFO - 'LANGUAGE': 'en_US:en', 09:14:25 INFO - 'LOGNAME': 'cltbld', 09:14:25 INFO - 'MAIL': '/var/mail/cltbld', 09:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:25 INFO - 'MOZ_NO_REMOTE': '1', 09:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:25 INFO - 'NO_EM_RESTART': '1', 09:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:25 INFO - 'PWD': '/builds/slave/test', 09:14:25 INFO - 'SHELL': '/bin/bash', 09:14:25 INFO - 'SHLVL': '1', 09:14:25 INFO - 'TERM': 'linux', 09:14:25 INFO - 'TMOUT': '86400', 09:14:25 INFO - 'USER': 'cltbld', 09:14:25 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:25 INFO - '_': '/tools/buildbot/bin/python'} 09:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:14:26 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 09:14:26 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 09:14:26 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 09:14:26 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 09:14:26 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 09:14:26 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 09:14:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:14:28 INFO - Installing collected packages: mozsystemmonitor 09:14:28 INFO - Running setup.py install for mozsystemmonitor 09:14:28 INFO - Successfully installed mozsystemmonitor 09:14:28 INFO - Cleaning up... 09:14:28 INFO - Return code: 0 09:14:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:28 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:28 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 09:14:28 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 09:14:28 INFO - Using env: {'DISPLAY': ':0', 09:14:28 INFO - 'HOME': '/home/cltbld', 09:14:28 INFO - 'LANG': 'en_US.UTF-8', 09:14:28 INFO - 'LANGUAGE': 'en_US:en', 09:14:28 INFO - 'LOGNAME': 'cltbld', 09:14:28 INFO - 'MAIL': '/var/mail/cltbld', 09:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:28 INFO - 'MOZ_NO_REMOTE': '1', 09:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:28 INFO - 'NO_EM_RESTART': '1', 09:14:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:28 INFO - 'PWD': '/builds/slave/test', 09:14:28 INFO - 'SHELL': '/bin/bash', 09:14:28 INFO - 'SHLVL': '1', 09:14:28 INFO - 'TERM': 'linux', 09:14:28 INFO - 'TMOUT': '86400', 09:14:28 INFO - 'USER': 'cltbld', 09:14:28 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:28 INFO - '_': '/tools/buildbot/bin/python'} 09:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:14:28 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 09:14:28 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 09:14:28 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 09:14:28 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 09:14:28 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 09:14:28 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 09:14:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:14:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:14:30 INFO - warning: no files found matching '*.txt' 09:14:30 INFO - no previously-included directories found matching 'build' 09:14:30 INFO - no previously-included directories found matching 'dist' 09:14:30 INFO - no previously-included directories found matching '.git*' 09:14:30 INFO - Installing collected packages: jsonschema, functools32 09:14:30 INFO - Running setup.py install for functools32 09:14:30 INFO - warning: no files found matching '*.txt' 09:14:30 INFO - no previously-included directories found matching 'build' 09:14:30 INFO - no previously-included directories found matching 'dist' 09:14:30 INFO - no previously-included directories found matching '.git*' 09:14:30 INFO - Successfully installed jsonschema functools32 09:14:30 INFO - Cleaning up... 09:14:31 INFO - Return code: 0 09:14:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:31 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:31 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 09:14:31 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 09:14:31 INFO - Using env: {'DISPLAY': ':0', 09:14:31 INFO - 'HOME': '/home/cltbld', 09:14:31 INFO - 'LANG': 'en_US.UTF-8', 09:14:31 INFO - 'LANGUAGE': 'en_US:en', 09:14:31 INFO - 'LOGNAME': 'cltbld', 09:14:31 INFO - 'MAIL': '/var/mail/cltbld', 09:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:31 INFO - 'MOZ_NO_REMOTE': '1', 09:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:31 INFO - 'NO_EM_RESTART': '1', 09:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:31 INFO - 'PWD': '/builds/slave/test', 09:14:31 INFO - 'SHELL': '/bin/bash', 09:14:31 INFO - 'SHLVL': '1', 09:14:31 INFO - 'TERM': 'linux', 09:14:31 INFO - 'TMOUT': '86400', 09:14:31 INFO - 'USER': 'cltbld', 09:14:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:31 INFO - '_': '/tools/buildbot/bin/python'} 09:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:14:31 INFO - Cleaning up... 09:14:31 INFO - Return code: 0 09:14:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:31 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:31 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 09:14:31 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 09:14:31 INFO - Using env: {'DISPLAY': ':0', 09:14:31 INFO - 'HOME': '/home/cltbld', 09:14:31 INFO - 'LANG': 'en_US.UTF-8', 09:14:31 INFO - 'LANGUAGE': 'en_US:en', 09:14:31 INFO - 'LOGNAME': 'cltbld', 09:14:31 INFO - 'MAIL': '/var/mail/cltbld', 09:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:31 INFO - 'MOZ_NO_REMOTE': '1', 09:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:31 INFO - 'NO_EM_RESTART': '1', 09:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:31 INFO - 'PWD': '/builds/slave/test', 09:14:31 INFO - 'SHELL': '/bin/bash', 09:14:31 INFO - 'SHLVL': '1', 09:14:31 INFO - 'TERM': 'linux', 09:14:31 INFO - 'TMOUT': '86400', 09:14:31 INFO - 'USER': 'cltbld', 09:14:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:31 INFO - '_': '/tools/buildbot/bin/python'} 09:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:14:31 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 09:14:31 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 09:14:31 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 09:14:31 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 09:14:31 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 09:14:31 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 09:14:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:14:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:14:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:33 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 09:14:34 INFO - Downloading docopt-0.6.1.tar.gz 09:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:14:34 INFO - Installing collected packages: blobuploader, requests, docopt 09:14:34 INFO - Running setup.py install for blobuploader 09:14:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:14:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:14:34 INFO - Running setup.py install for requests 09:14:34 INFO - Running setup.py install for docopt 09:14:34 INFO - Successfully installed blobuploader requests docopt 09:14:34 INFO - Cleaning up... 09:14:34 INFO - Return code: 0 09:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:34 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:34 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 09:14:34 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 09:14:34 INFO - Using env: {'DISPLAY': ':0', 09:14:34 INFO - 'HOME': '/home/cltbld', 09:14:34 INFO - 'LANG': 'en_US.UTF-8', 09:14:34 INFO - 'LANGUAGE': 'en_US:en', 09:14:34 INFO - 'LOGNAME': 'cltbld', 09:14:34 INFO - 'MAIL': '/var/mail/cltbld', 09:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:34 INFO - 'MOZ_NO_REMOTE': '1', 09:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:34 INFO - 'NO_EM_RESTART': '1', 09:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:34 INFO - 'PWD': '/builds/slave/test', 09:14:34 INFO - 'SHELL': '/bin/bash', 09:14:34 INFO - 'SHLVL': '1', 09:14:34 INFO - 'TERM': 'linux', 09:14:34 INFO - 'TMOUT': '86400', 09:14:34 INFO - 'USER': 'cltbld', 09:14:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:34 INFO - '_': '/tools/buildbot/bin/python'} 09:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:35 INFO - Running setup.py (path:/tmp/pip-oSIWCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:35 INFO - Running setup.py (path:/tmp/pip-Oo9i0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:35 INFO - Running setup.py (path:/tmp/pip-UEBhkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:35 INFO - Running setup.py (path:/tmp/pip-esFtWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:35 INFO - Running setup.py (path:/tmp/pip-JtVgsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:35 INFO - Running setup.py (path:/tmp/pip-c2KC1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:35 INFO - Running setup.py (path:/tmp/pip-F47gnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:35 INFO - Running setup.py (path:/tmp/pip-QiVxtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:35 INFO - Running setup.py (path:/tmp/pip-aZI3fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:35 INFO - Running setup.py (path:/tmp/pip-HJVixo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:35 INFO - Running setup.py (path:/tmp/pip-lEMo7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:35 INFO - Running setup.py (path:/tmp/pip-ImEIyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:35 INFO - Running setup.py (path:/tmp/pip-U1nlev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:35 INFO - Running setup.py (path:/tmp/pip-U1DK6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:36 INFO - Running setup.py (path:/tmp/pip-rOP8hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:36 INFO - Running setup.py (path:/tmp/pip-_N0hLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:36 INFO - Running setup.py (path:/tmp/pip-vODPVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:14:36 INFO - Running setup.py install for manifestparser 09:14:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:14:36 INFO - Running setup.py install for mozcrash 09:14:36 INFO - Running setup.py install for mozdebug 09:14:36 INFO - Running setup.py install for mozdevice 09:14:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:14:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:14:36 INFO - Running setup.py install for mozfile 09:14:36 INFO - Running setup.py install for mozhttpd 09:14:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:14:36 INFO - Running setup.py install for mozinfo 09:14:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:14:36 INFO - Running setup.py install for mozInstall 09:14:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Running setup.py install for mozleak 09:14:37 INFO - Running setup.py install for mozlog 09:14:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Running setup.py install for moznetwork 09:14:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Running setup.py install for mozprocess 09:14:37 INFO - Running setup.py install for mozprofile 09:14:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Running setup.py install for mozrunner 09:14:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:37 INFO - Running setup.py install for mozscreenshot 09:14:37 INFO - Running setup.py install for moztest 09:14:37 INFO - Running setup.py install for mozversion 09:14:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:38 INFO - Cleaning up... 09:14:38 INFO - Return code: 0 09:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:38 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:38 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 09:14:38 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 09:14:38 INFO - Using env: {'DISPLAY': ':0', 09:14:38 INFO - 'HOME': '/home/cltbld', 09:14:38 INFO - 'LANG': 'en_US.UTF-8', 09:14:38 INFO - 'LANGUAGE': 'en_US:en', 09:14:38 INFO - 'LOGNAME': 'cltbld', 09:14:38 INFO - 'MAIL': '/var/mail/cltbld', 09:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:38 INFO - 'MOZ_NO_REMOTE': '1', 09:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:38 INFO - 'NO_EM_RESTART': '1', 09:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:38 INFO - 'PWD': '/builds/slave/test', 09:14:38 INFO - 'SHELL': '/bin/bash', 09:14:38 INFO - 'SHLVL': '1', 09:14:38 INFO - 'TERM': 'linux', 09:14:38 INFO - 'TMOUT': '86400', 09:14:38 INFO - 'USER': 'cltbld', 09:14:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:38 INFO - '_': '/tools/buildbot/bin/python'} 09:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:38 INFO - Running setup.py (path:/tmp/pip-eEnjSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:38 INFO - Running setup.py (path:/tmp/pip-wHSD4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:38 INFO - Running setup.py (path:/tmp/pip-2Nc5Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:38 INFO - Running setup.py (path:/tmp/pip-Ex5qAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:38 INFO - Running setup.py (path:/tmp/pip-Geb6qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:38 INFO - Running setup.py (path:/tmp/pip-zqxbl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:38 INFO - Running setup.py (path:/tmp/pip-L5eXsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:38 INFO - Running setup.py (path:/tmp/pip-naM3YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:38 INFO - Running setup.py (path:/tmp/pip-3bJGbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:38 INFO - Running setup.py (path:/tmp/pip-F0nwYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:38 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)) 09:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:38 INFO - Running setup.py (path:/tmp/pip-KcKcNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:39 INFO - Running setup.py (path:/tmp/pip-TtEeHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:39 INFO - Running setup.py (path:/tmp/pip-H0_Bey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:39 INFO - Running setup.py (path:/tmp/pip-Y_zGEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:39 INFO - Running setup.py (path:/tmp/pip-iOpm_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:39 INFO - Running setup.py (path:/tmp/pip-JmqqHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:39 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)) 09:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:39 INFO - Running setup.py (path:/tmp/pip-8eBhbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:39 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)) 09:14:39 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)) 09:14:39 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)) 09:14:39 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)) 09:14:39 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)) 09:14:39 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)) 09:14:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:41 INFO - Downloading blessings-1.6.tar.gz 09:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:41 INFO - Installing collected packages: blessings 09:14:41 INFO - Running setup.py install for blessings 09:14:41 INFO - Successfully installed blessings 09:14:41 INFO - Cleaning up... 09:14:41 INFO - Return code: 0 09:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:41 INFO - Reading from file tmpfile_stdout 09:14:41 INFO - Current package versions: 09:14:41 INFO - argparse == 1.2.1 09:14:41 INFO - blessings == 1.6 09:14:41 INFO - blobuploader == 1.2.4 09:14:41 INFO - docopt == 0.6.1 09:14:41 INFO - functools32 == 3.2.3-2 09:14:41 INFO - jsonschema == 2.5.1 09:14:41 INFO - manifestparser == 1.1 09:14:41 INFO - mozInstall == 1.12 09:14:41 INFO - mozcrash == 1.0 09:14:41 INFO - mozdebug == 0.1 09:14:41 INFO - mozdevice == 0.49 09:14:41 INFO - mozfile == 1.2 09:14:41 INFO - mozhttpd == 0.7 09:14:41 INFO - mozinfo == 0.9 09:14:41 INFO - mozleak == 0.1 09:14:41 INFO - mozlog == 3.4 09:14:41 INFO - moznetwork == 0.27 09:14:41 INFO - mozprocess == 0.23 09:14:41 INFO - mozprofile == 0.28 09:14:41 INFO - mozrunner == 6.13 09:14:41 INFO - mozscreenshot == 0.1 09:14:41 INFO - mozsystemmonitor == 0.3 09:14:41 INFO - moztest == 0.7 09:14:41 INFO - mozversion == 1.4 09:14:41 INFO - psutil == 3.1.1 09:14:41 INFO - requests == 1.2.3 09:14:41 INFO - wsgiref == 0.1.2 09:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:41 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:41 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 09:14:41 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 09:14:41 INFO - Using env: {'DISPLAY': ':0', 09:14:41 INFO - 'HOME': '/home/cltbld', 09:14:41 INFO - 'LANG': 'en_US.UTF-8', 09:14:41 INFO - 'LANGUAGE': 'en_US:en', 09:14:41 INFO - 'LOGNAME': 'cltbld', 09:14:41 INFO - 'MAIL': '/var/mail/cltbld', 09:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:41 INFO - 'MOZ_NO_REMOTE': '1', 09:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:41 INFO - 'NO_EM_RESTART': '1', 09:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:41 INFO - 'PWD': '/builds/slave/test', 09:14:41 INFO - 'SHELL': '/bin/bash', 09:14:41 INFO - 'SHLVL': '1', 09:14:41 INFO - 'TERM': 'linux', 09:14:41 INFO - 'TMOUT': '86400', 09:14:41 INFO - 'USER': 'cltbld', 09:14:41 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:41 INFO - '_': '/tools/buildbot/bin/python'} 09:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 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)) 09:14:42 INFO - Cleaning up... 09:14:42 INFO - Return code: 0 09:14:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:42 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 0x7fb13edefe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', '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 09:14:42 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 09:14:42 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 09:14:42 INFO - Using env: {'DISPLAY': ':0', 09:14:42 INFO - 'HOME': '/home/cltbld', 09:14:42 INFO - 'LANG': 'en_US.UTF-8', 09:14:42 INFO - 'LANGUAGE': 'en_US:en', 09:14:42 INFO - 'LOGNAME': 'cltbld', 09:14:42 INFO - 'MAIL': '/var/mail/cltbld', 09:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:42 INFO - 'MOZ_NO_REMOTE': '1', 09:14:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:42 INFO - 'NO_EM_RESTART': '1', 09:14:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:42 INFO - 'PWD': '/builds/slave/test', 09:14:42 INFO - 'SHELL': '/bin/bash', 09:14:42 INFO - 'SHLVL': '1', 09:14:42 INFO - 'TERM': 'linux', 09:14:42 INFO - 'TMOUT': '86400', 09:14:42 INFO - 'USER': 'cltbld', 09:14:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:42 INFO - '_': '/tools/buildbot/bin/python'} 09:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:42 INFO - Cleaning up... 09:14:42 INFO - Return code: 0 09:14:42 INFO - Running post-action listener: _resource_record_post_action 09:14:42 INFO - Running post-action listener: _start_resource_monitoring 09:14:42 INFO - Starting resource monitoring. 09:14:42 INFO - [mozharness: 2016-12-10 17:14:42.434443Z] Finished create-virtualenv step (success) 09:14:42 INFO - [mozharness: 2016-12-10 17:14:42.434956Z] Running install step. 09:14:42 INFO - Running pre-action listener: _resource_record_pre_action 09:14:42 INFO - Running main action method: install 09:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:42 INFO - Reading from file tmpfile_stdout 09:14:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:42 INFO - Reading from file tmpfile_stdout 09:14:42 INFO - Output received: 09:14:42 INFO - Usage: mozinstall [options] installer 09:14:42 INFO - Options: 09:14:42 INFO - -h, --help show this help message and exit 09:14:42 INFO - -d DEST, --destination=DEST 09:14:42 INFO - Directory to install application into. [default: 09:14:42 INFO - "/builds/slave/test"] 09:14:42 INFO - --app=APP Application being installed. [default: firefox] 09:14:42 INFO - mkdir: /builds/slave/test/build/application 09:14:42 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'] 09:14:42 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 09:14:58 INFO - Reading from file tmpfile_stdout 09:14:58 INFO - Output received: 09:14:58 INFO - /builds/slave/test/build/application/firefox/firefox 09:14:58 INFO - Running post-action listener: _resource_record_post_action 09:14:58 INFO - [mozharness: 2016-12-10 17:14:58.781727Z] Finished install step (success) 09:14:58 INFO - [mozharness: 2016-12-10 17:14:58.781966Z] Running run-tests step. 09:14:58 INFO - Running pre-action listener: _resource_record_pre_action 09:14:58 INFO - Running main action method: run_tests 09:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:58 INFO - Python 2.7.3 09:14:58 INFO - Return code: 0 09:14:58 INFO - grabbing minidump binary from tooltool 09:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:58 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 0x21fef20>, '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 0x216bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:58 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 09:14:58 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 09:14:58 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 09:14:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:58 INFO - Return code: 0 09:14:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:14:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:14:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 09:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 09:14:58 INFO - Using env: {'DISPLAY': ':0', 09:14:58 INFO - 'HOME': '/home/cltbld', 09:14:58 INFO - 'LANG': 'en_US.UTF-8', 09:14:58 INFO - 'LANGUAGE': 'en_US:en', 09:14:58 INFO - 'LOGNAME': 'cltbld', 09:14:58 INFO - 'MAIL': '/var/mail/cltbld', 09:14:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:58 INFO - 'MOZ_NO_REMOTE': '1', 09:14:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:58 INFO - 'NO_EM_RESTART': '1', 09:14:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:58 INFO - 'PWD': '/builds/slave/test', 09:14:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:58 INFO - 'SHELL': '/bin/bash', 09:14:58 INFO - 'SHLVL': '1', 09:14:58 INFO - 'TERM': 'linux', 09:14:58 INFO - 'TMOUT': '86400', 09:14:58 INFO - 'USER': 'cltbld', 09:14:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670', 09:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:58 INFO - '_': '/tools/buildbot/bin/python'} 09:14:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 09:14:59 INFO - mozversion application_buildid: 20161210082451 09:14:59 INFO - mozversion application_changeset: 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 09:14:59 INFO - mozversion application_display_name: Nightly 09:14:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:59 INFO - mozversion application_name: Firefox 09:14:59 INFO - mozversion application_remotingname: firefox 09:14:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:14:59 INFO - mozversion application_vendor: Mozilla 09:14:59 INFO - mozversion application_version: 53.0a1 09:14:59 INFO - mozversion platform_buildid: 20161210082451 09:14:59 INFO - mozversion platform_changeset: 5cc901aa30a2f8ce528082f8b5a4f06c34fef363 09:14:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:14:59 INFO - mozversion platform_version: 53.0a1 09:14:59 INFO - using testdate: 1481390099 09:14:59 INFO - actual date: 1481390099 09:14:59 INFO - starting webserver on 'localhost:60107' 09:14:59 INFO - SUITE-START | Running 2 tests 09:14:59 INFO - TEST-START | dromaeo_css 09:14:59 INFO - operating with platform_type : linux_ 09:14:59 INFO - Initialising browser for dromaeo_css test... 09:14:59 INFO - TEST-INFO | started process 4000 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzFfU2l/profile http://localhost:60107/getInfo.html) 09:15:08 INFO - PROCESS | 4000 | __metrics Screen width/height:1600/1200 09:15:08 INFO - PROCESS | 4000 | colorDepth:24 09:15:08 INFO - PROCESS | 4000 | Browser inner width/height: 1024/697 09:15:08 INFO - PROCESS | 4000 | __metrics 09:15:08 INFO - PROCESS | 4000 | 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" 09:15:08 INFO - PROCESS | 4000 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:09 INFO - PROCESS | 4000 | console.error: PushService: 09:15:09 INFO - PROCESS | 4000 | stateChangeProcessEnqueue: Error transitioning state 09:15:09 INFO - PROCESS | 4000 | UnknownError 09:15:10 INFO - TEST-INFO | 4000: exit 0 09:15:10 INFO - Browser initialized. 09:15:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:15:10 INFO - TEST-INFO | started process 4080 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzFfU2l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:22 INFO - PROCESS | 4080 | RSS: Main: 175652864 09:15:22 INFO - PROCESS | 4080 | 09:16:13 INFO - PROCESS | 4080 | 1481390173047 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] 09:18:27 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60107/tests/dromaeo/cssquery-ext.html) 09:18:29 INFO - PROCESS | 4080 | RSS: Main: 315842560 09:18:29 INFO - PROCESS | 4080 | 09:21:31 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-ext.html (next: http://localhost:60107/tests/dromaeo/cssquery-jquery.html) 09:21:32 INFO - PROCESS | 4080 | RSS: Main: 267849728 09:21:32 INFO - PROCESS | 4080 | 09:24:34 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60107/tests/dromaeo/cssquery-mootools.html) 09:24:35 INFO - PROCESS | 4080 | RSS: Main: 254189568 09:24:35 INFO - PROCESS | 4080 | 09:27:37 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60107/tests/dromaeo/cssquery-prototype.html) 09:27:38 INFO - PROCESS | 4080 | RSS: Main: 255467520 09:27:38 INFO - PROCESS | 4080 | 09:30:41 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60107/tests/dromaeo/cssquery-yui.html) 09:30:42 INFO - PROCESS | 4080 | RSS: Main: 219918336 09:30:42 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | Cycle 1(1): loaded http://localhost:60107/tests/dromaeo/cssquery-yui.html (next: http://localhost:60107/tests/dromaeo/cssquery-dojo.html) 09:33:45 INFO - PROCESS | 4080 | RSS: Main: 316698624 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | __start_tp_report 09:33:45 INFO - PROCESS | 4080 | _x_x_mozilla_page_load 09:33:45 INFO - PROCESS | 4080 | _x_x_mozilla_page_load_details 09:33:45 INFO - PROCESS | 4080 | |i|pagename|runs| 09:33:45 INFO - PROCESS | 4080 | |0;dojo.html;2560.5391029614675;2588.2105957682925;2615.803814713897;2625;2633.2758491414847;4606.792694328755;4654.022655242399;4662.8601141965755;4664.953350466494;4681.438227412711;4660.813567457298;4661.091087237998;4685.882852928678;4704.0356726871;4706.22347312693;4270.829166833323;4281.678874084456;4318.169452289911;4321.6326612238;4390.078083602447;4004.3712058847536;4388.166248412791;4402.669799765014;4413.3379993001145;4461.7992190351515;4198.6431153352005;4222.472190976128;4379.1022840317655;4432.534583868712;4482.596566309032;4239.894002649928;4273.465816772903;4277.914441711148;4278.122984788142;4282.379055037002;4245.936310955338;4255.680823938186;4255.744655320691;4256.7445953242905;4259.084296876155;4582.702124361906;4603;4609.03210325832;4610.792514336863;4613.008203236287;4872.6832755870755;4927.43334516528;4971.353724015855;4979.0788704089855;5018.372703412074;5070.340855688737;5097;5101.821436249714;5116.334876466036;5118.411382691001;4710.905781884343;4711.081339138904;4715.599174070208;4715.622750180046;4758.928616070762;5078.822241221539;5174.456682048406;5178.844634660966;5184.611154163453;5240.318758561362;4789.042191561702;4814.614830813526;4818.518148185153;4918.139325618003;4944.752762361867;5054;5057.190849464068;5058.46886076964;5060.190369540929;5112.335396398445;4993.300937868701;5002.049610573979;5006.899862002812;5012.423571289275;5053.2420136979745;4876.195427754381;4906.239532872411;4908.631852611068;4973.825916092919;5027.924581131286;4276.615104640535;4293.2701440755145;4295.312749959991;4297.978510107429;4301.44081269433;3704.073981504624;3716.851325946932;3779.433085037266;3806.1245913440066;3809.371453710107;4321.395004699345;4328.026194106301;4334.0248444099825;4370.672199585044;4372.453443319585;4997.075541024922;5008.774605142778;5010.824621138243;5014.498550144956;5029.421616514074;4226.788660566959;4262.403263543107;4273.87178384649;4309.353596960418;4314.805833737427;4510.932336014962;4535.909281814345;4543.182227199136;4576.26779715244;4584.977075114603;4678.532146785362;4689.507601701772;4691.601213896721;4697.342372067981;4714.127886341038;4918.754062296943;4936.531029552187;5026.3214466048075;5035.974820125876;5073.365829271341;4610.147122782162;4638.373819534455;4649.0469453761225;4684.976575117103;4719.551642593948;4930.186519224288;4931.50684931504;4932.087563800708;4938.703677779345;4983.551480366785;5001.57486613642;5002.199648056294;5004.049230646166;5014.899702006012;5046.243063540498;4788.401449818773;4827.396575428072;4829.613630909448;4830.903381932412;4849.393825771778;4881.560659540658;4919.3604831371695;4926.285688575065;4926.5073492650445;4931.950680493149;4793.041391721739;4803.519648035169;4803.639727020418;4803.759812009455;4846.878828029327;4825.686330388514;4898.436679781871;4901.068796928572;4906.411230652345;4949.30709700635;4697.436307643105;4733.290006499053;4740.19416699155;4757.3101900224965;4814.8796280093275 09:33:45 INFO - PROCESS | 4080 | |1;ext.html;525.3459442993474;526.2448386565277;550.3379793025067;571.240250466879;584.2667452347306;27086.458270834595;36683.63316366835;37976.240475190476;38084.80957595215;41567.16865662693;37504.81247593758;37810.43284350737;37836.43245351315;37856;37873.43189852148;7214.06217191767;7532.4350673699555;7704.6918123275;7705.075390953093;7714.845703085963;7314.451416143784;9656.082672146134;9873.753156171117;9889.208863290956;10224.897751022469;9958.701238962843;10027.64903228387;10029;10032.59869605215;10048.346857454279;9811.754706132333;9834.754131146707;9862.556184971621;9887.703368898945;9891.752706182331;9733.323336082563;9816.950915245377;9817.410955342631;9826.80346393068;9833.31166818323;36505;36779;36811.63188368109;36811.815940920394;36826.63173368286;3033.211365045082;3150.6376766671897;3175.42842288388;3233.143217047484;3360.663933606644;27220;28008.299792505146;28254;28360.574591381144;28503;3495.755297129202;3508;3529.170644898447;3585.8027808470524;3619.8826064343457;169736;170019;170112;170550;171386.28761705753;50295.49704502908;51502.22746658803;51561.48438515642;51568.22647660288;51624.741876290376;54447.72776136173;54619;54634.45365546373;54650.72674636601;54652.726736366065;11613.30320180775;12094.455749491293;12098.81851772224;12121.090918181171;12125.514979400725;8555.144485551395;8861.113888611088;8876.955615222012;9308.255339572817;9390.765230869283;15667.2949717263;15976.600584985235;16042.919785400998;16085.517434476984;16161.515154545383;5585.273914391184;5710.800121995793;5739.540836733051;5794.188813566104;5818.592698511064;784.9764507064797;850.0734199722209;881.6517475597232;931.5295775633261;943.7593413679605;25563.61654575183;25693.743062569134;26077.087301944342;26246.34384140412;26987;909.5134103254727;944.3059351376733;970.3256236915418;986.980260394788;993.7167907146573;13726.794098088536;13952.441902323793;13966.720665586834;14061.296935153201;14154.36305366261;326356;328656;328722;329428;329766;47012.7649361751;47147;47214;47223;47229.76385118053;1050.9632162874416;1093.5625749700184;1094.7372630568766;1158.1661203933154;1163.5520324674892;22475.325740227167;29350.706492934798;30012.849935750182;30052.84973575118;30197.849010754806;44333.556664432945;44969.775151124035;45345.77327113343;45387.319190211514;45392.31911521264;299053;302358;302698;303439;303786;30396.84801575978;31192.22019449532;34890.65109348975;35907.46138807868;38440.42339364856;38589.035274118374;39464.80267598644;39482.60517394904;39879.40180897231;40275.1944961105;32719.672803272613;32974.17564560905;33042.504362434105;33243.833780830944;33259;32476.512852307707;33228.50157247595;33451.83274083614;33563.13823163138;33966.32067358688;33227.00318990434;33347.49978750369;33423.665763342055;33472.318045014545;33940.321193576485;27431.725682742916;29489.88185111577;30564.694353057075;30635.54046689346;30808.383832323678;21484.67772983375;22498.88750556237;23243.186488472824;23834.16580419677;23923.16268930579 09:33:45 INFO - PROCESS | 4080 | |2;jquery.html;353.8637624514561;356.80554098016586;357.1802712774183;370.1689706608664;381.6946442845723;14734.115953042825;15597.220138993047;16495.917520412397;16604;16628.085455299955;15548.06711597305;15814.4464943727;16305.184740762974;16475.83524164758;16577.585560360996;4469.687121901468;4833.879153021167;4847.733374664392;4858.441279252878;4865.513448655124;3600.6039335673054;3778.433235014756;4740.431148262213;4849.903001939959;5339.866503337428;5608.523275521586;5666.688332141731;5749.511291540203;5749.59752817303;5760;5654.6041777075825;5712.2574065371655;5724.771009159628;5833.620814647077;5873.882522349571;5166.483351664841;5167.664101833407;5169.8449046528285;5173.198154286091;5174.09453345663;19386.4184074478;20504.257138143275;20674.689879651665;20904.163833446644;20949.68575471369;1893.933712320062;1980.4553747719349;1988.4730546405215;2072.7098206251135;2648.4835457085874;13155.802662960063;13728;13838.169709817343;14638.487652932203;14852.344240876884;1764.9647007059787;1822.7174787907895;1858.6654402207598;1881.6801143805585;1891.8864868107958;70612;72818.27181728168;74010;74189.62905185494;75410;15721;15921.840781592267;16193.190340483165;16209.351625935067;16402;27706.445871082466;27837.304067398476;27885;27904.581431278548;27944.44111117807;7840.803979900434;7969.083555391204;8019.558924259221;8246.958765206136;8336.583170841555;6447.1618689571;6510.576812507172;6517.902231466532;6694.464442844561;6735;7944.483608565425;8163;8173.959130204311;8279.50322980623;8336.416450848503;2540.479201763634;3181;3296.208909861664;3349.648286929886;3358.294758100826;701.9122609673791;853.0337571896837;853.7737499562621;894.8031433084841;956.4022485946283;14129.717405651829;15525.922370388076;15688;16354.509364719077;17014;802.12968928713;819.6516480495766;842.1241908415302;842.726114012936;884.12471653063;8540.70107546233;8603.784905377415;8995.865062024075;9031.683891063782;9212.216961558208;325801;328891;329162;331046;331692;65891;76797;76894.615526922;76981;79853;871.215905684876;901.5717534171216;910.0899100898969;910.717677519971;911.49867572836;14772.63068423298;15083.924580377028;15093.320800564221;15182.544523664308;15864.60338491547;31708.84145579257;35602.46596301006;35650.643493564734;36537.45193822148;36575.86382570624;46084.53915460803;46948.7652561735;53354.4664553365;53601.731991339795;54368.728156358964;7006.5095443320515;7307.817304567428;7363.337299643058;7502.474826762293;7915.287624113857;10855.294405863593;11365.261258018203;11466.598669046543;11921.284722916653;12409.069319801232;5497.780088796403;5653;9690.079442452956;10202.744931376776;10500.842487362543;9476.14714675655;10116.494175291353;10265.435401053013;10459.895401045893;10506.106980906708;9475.715728528156;9702.417854928726;9705.563249653642;10174.74563135898;10892.292001019909;6625.734970601122;6637.336266373517;6755.864882702417;6883.380495755406;7095.9645201773665;7728;10346.741331466774;10350.275480716278;10358.741031474274;10477.580896764044 09:33:45 INFO - PROCESS | 4080 | |3;mootools.html;1332.2872263339113;1347.299404309759;1400.2018849255928;1579.368252698921;1616.6443382455864;6578.276389597152;6745.055692203083;7024.789256322312;7479.738209162673;7497.737579184723;6889.448844092473;7263.927360726391;7273.490855640107;7381.520201186932;7468.701251949929;2082.6771850363216;2099.737532808399;2116.5766846630736;2116.9259075932346;2121.395402310342;1771.8848274862157;1826.0960824391946;2059.41379054037;2085.4265077103764;2158.417227348615;1973.1614064022804;2036.9490762730902;2042.5710600773855;2051.8050785175383;2057.537054162809;2021.7877122902105;2024.423039433769;2033.613613413454;2038.1745393115812;2060.6702927531674;2056.979430205694;2085.8644188127723;2088.822450091744;2089.1330098009307;2097.9370618881458;7301.963490182515;7338.669759860819;7351.926480735177;7356.77929662111;7358.117025956866;4270.978645106786;4356.302991521341;4359.825606975717;4361.8441113105055;4387.056782791715;2640.366312085105;2710.7966902482194;2866.125831621355;2873.0806142034558;2880.8825513247957;859.8194379180379;871.4117970369975;874.2437791310471;1715.133857402004;1746.2142036083685;9536.904630953672;9671.564779584865;9682.515874206332;9720.319577629569;9814.90185098147;3740.345439548068;3910.1006768443417;3912.0611053346993;3913.7847418391693;3930.0960779020415;7828.060632723996;7834.921650783419;7842.098158711707;7843.568603726736;7850.646720897574;4421.579949904753;4424.513303536608;4429.379886815848;4431.423914891044;4434.778261086933;4000.7199496035;4013.297672907288;4054.594540545922;4066.8169932353117;4067.983004248938;3021.8942337018098;3022.833744144093;3079.8306093164642;3104.3170502489415;3114.8442577871015;909.4270609515962;930.8417569013149;934.5654270764128;942.3356533643902;946.3990366117565;680.669875110571;685.4584877946467;735.724103461202;742.0687037767567;768.9692412303557;4764.571188593043;4776.450708168605;4800;4927.704337739747;4931.5561599455505;801.7674874286508;806.9717559885376;817.3257062922993;822.5434883639638;861.1517655109712;3271.6401195868434;3272.6236482804784;3335.533025376449;3355.9496607550905;3384.830758462067;8532.616032278562;9085.636574536944;9097.590608422637;9097.727068187965;9106.86339704905;9062.954685226663;9065.138811812867;9073.500957447404;9075.2286055686;9080.22818060459;6796.626185559743;6914.896276555855;6994.405475534535;7004.2645522220455;7030.859382812315;4940.431850337257;4957.281194226889;4976;4983.900321993612;5072.565168719109;8227.83544329122;8253.546054967035;8258.587070646563;8317.667293308199;8324.334053275845;8442.282405995558;8465.28045116172;8474.703385381483;8507.361947853811;8543.530105844238;5994.10088486713;6052.636841789506;6132.969335153296;6151.0773383992755;6151.477124444473;6866.416354609914;6963.2340442552295;7004.719811207495;7031.226565077735;7036.8592628146125;5543.334799824047;6077.3011103721865;6217.378262173927;6232.096346029891;6385.106085148176;6001.2498437695285;6020.337762845996;6062.36345183767;6140.3245642978345;6190.690465476618;5991.2510936132985;6014.969925150346;6026.7287972040485;6031.09533569968;6093.817185484442;4433.911321773482;4453.55464453552;4481.260592002283;4495.820167193407;4544.386507821402;4204.1801848639225;4280;4295.892602684958;4402.9559704402545;4564.589186973189 09:33:45 INFO - PROCESS | 4080 | |4;prototype.html;320.7289840085129;337.20924432206476;337.55779928293936;341.882050692511;344.77761843610864;4601.446197360464;4656.719402164402;4696.624270058395;4731.888006318512;4747.145513807513;4602.148602508539;4675.602573781224;4690.366800481934;4714.4106986626675;4773.80904763809;3845.3078445879732;3849.845046486057;3854.6530812226824;3859.5561510426237;3864.1305706216017;3847.8845634630866;3852.595477474867;3864.7101467389916;3865.246276975991;3872.4384964180176;3578.14124610093;3667.174885650734;3668.963310366902;3681.9815900920594;3701.555813302394;3664.670179683841;3665.3505922335057;3668.504751858505;3671.229041901204;3674.6325367463305;3176.3806057818733;3197.936041279185;3210.811999560154;3233.96766032339;3241.594800649919;4432.423784907974;4598.287265473857;4599.977000115012;4614.792334344963;4620.475243169746;4152.979235103835;4177.4778152730905;4183.142455796585;4184.330507118846;4196.580341965779;4955.653104282702;4958.380202474691;4958.380202474691;4958.504149585049;4975.850724478271;3499.8275577681507;3519.2961407718303;3534.8586056557706;3539.5752509698746;3542.734294927891;4927.334809800703;5147.588192944518;5147.897042059175;5151.5363617274625;5179;3552.946705799415;3568.91077723059;3570.8214589270433;3582.7492075554983;3586.30067136911;3621.909452263662;3630.3102410541915;3643.4899114123514;3645.708343332527;3648.4892115103908;3989.3218152914073;4179.561146079618;4185.874423767292;4186.162767446523;4192.161567686475;4967.3045773591;4995.075910956485;5008.6744361616375;5012.624053195952;5016.222485514678;1172.9589464368705;1177.4701384376892;1197.6167626359481;1235.8887700106854;1243.7823380908485;814.3159745813545;828.5153185386496;838.2288294768828;839.7312859884901;846.7629063862129;4188.937165942513;4235.25882970479;4248.4477017987465;4254.021575037758;4271.722338048017;4953.554180123806;4955.860152165023;4966.279889416087;4979.053979743837;4992.2761199626575;4303.33298338758;4312.849050283287;4313.61177494027;4319.827206911751;4325.653947684178;2529.6205569164404;2655.150351887416;2655.221001928712;2696.5955106734145;2699.784017278613;4500.842470513517;4562.361269422284;4606.631469482433;4664.230401983703;4715.0805592909055;5062.544371006554;5080.628230377777;5099;5106.259592359087;5108.386993560796;4503.909921801545;4577.679562430598;4621.214393553104;4636.489986101594;4668.953310466852;4984.7258400788305;5010.448850626355;5010.888893328857;5011.4236862761245;5031.6729412588065;4850.878728031827;4864.659473836798;4887.584555312838;4899.926501102415;4921.557059864629;4489.720429677526;4632.8146874126005;4647.8140874366;4650.651201159859;4663.300504924288;3697.5193224880595;3715.554133503997;3715.866660668414;3726.4037753959237;3734.738568300193;4921.26181072842;4924.335214745964;4975.626827987988;4984.925226121539;4987.501249874984;4618.769061546842;4743.596794272387;4887.804487820447;4965.776540055634;4983.551480366785;4954.65317427791;4958.305837182869;4968.726720030433;4970.676906001098;4972.328735620789;4762.214234651244;4898.21628539432;4900.411950565955;4903.656744027884;4906.239532872411;4963;4979.975100124476;4996.60027197816;4998.425181104074;4999.825041115408;4859.684120532154;4868.878278043077;4872.537108974641;4875.24433712775;4879.926416188433 09:33:45 INFO - PROCESS | 4080 | |5;yui.html;625.2434554189431;658.1049772309658;659.9538032337737;662.3674390956637;726.0778810910142;12238.143329966906;13437.865621343784;13438.664033399169;13452.865471345283;13509.054366194357;12971.416286267116;12977.351132443364;12978.54575089872;12978.675533111676;12992.220466771976;619.4889216396468;639.063771574643;761.1247065874234;762.1844626249915;775.5850619918344;771.0285040848543;781.1953687701676;785.6857257097149;785.7132146766446;785.7249962513132;816.4774544291658;822.2928281677753;822.5804839531852;826.2481142160635;828.4283844147549;645.7126578672493;665.7436886798569;789.6091434739841;817.370624619046;817.889584906036;593.3562085137631;593.7031484257872;594.0880748051763;594.3432507079701;594.9974293315777;12804.61586152417;12820.743585128339;12823.61529154127;12827.486900524062;12845.935770321183;12168.878311216864;12286.262824230485;12371.319577423239;12650.114491985565;12658.55695050678;11076.667699969013;11101.777964440747;11229.101671866312;11440.542378304857;11595.768084638345;3941.921161576752;3975.1254723960683;4011.1576568920564;4069.837206511736;4166.375043743433;60233;60771.69614151901;62859;63259.36740632581;63755;9915.355501892354;9925.404475731479;10045.79908401828;10109.595616175271;10370.429626370622;17190.226439810238;17229.91385043067;17271.222794974005;17400.39098631542;17487.562810929576;6261.749530018748;6320.652364120017;6348.460380867678;6365.777197798055;6513.446357059608;3376.9493457598155;3387.085486918518;3403.2002479417315;3466.791992480459;3562.8040457775073;2668.3862711576444;2722.68689100752;2742.8491432970977;2743.8079334446397;2744.8627568621887;459.1459884614614;461.5107985535343;464.1204916682934;479.00606242047195;481.95421434963623;441.6599218601723;453.36981595582165;525.4561528817732;535.5287347134574;565.1579147070835;7509.324160825552;7605.315521603082;7705.460617756816;7727.343175830118;7783.4162437817395;520.5471240021205;548.6132276744885;568.7582777319623;586.3345103307777;610.575649923299;3481.5473988381855;3714.9442758358646;3814.351560234711;3864.091938394475;3934.940975885364;48813.26780098301;49881.50118498841;59119;61281.38718612846;61549.384506155264;51586.74206628943;51833.2225016625;52785;53517.1972420414;53743.731281344124;47404.525954740704;47714.28428573574;48284.999999999294;48514.75742621335;50671.74664126656;8378.329733621174;8628.956855215934;8915.331350148635;8966.775830604287;9000.954995224982;30813.845930770203;31206;31431.685683142874;34728.479072813425;34744.82627586846;19995.700064498753;21920.671189931843;31050.223744406572;43219.78390108155;44730.55269447264;9583.856242156513;10077.798444031225;10517.263791534826;10999.505022274177;11091.112710982941;11544.826827597424;11750.536525889624;11812.76374472523;11954.163208575315;12100.697482563006;11874.881251187378;11993.76012479728;12125.81811272814;12195;12548.05889558305;11690.357030363411;12040.397980100784;12070.215435996632;12225.388730563258;12253.632391028283;11151.330920144817;11596.188266821242;11744.58893938708;11771.352575608082;11917.940410297893;9769.85345219808;10014.499275036365;10301.536430860479;10455.581776728846;10556.102731267621;8972.685955991508;9121.45271283725;9643.855342170013;9965.053319934595;9979.401235925867 09:33:45 INFO - PROCESS | 4080 | __end_tp_report 09:33:45 INFO - PROCESS | 4080 | __start_cc_report 09:33:45 INFO - PROCESS | 4080 | _x_x_mozilla_cycle_collect,1364 09:33:45 INFO - PROCESS | 4080 | __end_cc_report 09:33:45 INFO - PROCESS | 4080 | __startTimestamp1481391225433__endTimestamp 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | ------- Summary: start ------- 09:33:45 INFO - PROCESS | 4080 | Number of tests: 6 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#0] dojo.html Cycles:165 Average:4625.42 Median:4715.60 stddev:483.82 (10.3%) stddev-sans-first:457.38 09:33:45 INFO - PROCESS | 4080 | Values: 2560.5 2588.2 2615.8 2625.0 2633.3 4606.8 4654.0 4662.9 4665.0 4681.4 4660.8 4661.1 4685.9 4704.0 4706.2 4270.8 4281.7 4318.2 4321.6 4390.1 4004.4 4388.2 4402.7 4413.3 4461.8 4198.6 4222.5 4379.1 4432.5 4482.6 4239.9 4273.5 4277.9 4278.1 4282.4 4245.9 4255.7 4255.7 4256.7 4259.1 4582.7 4603.0 4609.0 4610.8 4613.0 4872.7 4927.4 4971.4 4979.1 5018.4 5070.3 5097.0 5101.8 5116.3 5118.4 4710.9 4711.1 4715.6 4715.6 4758.9 5078.8 5174.5 5178.8 5184.6 5240.3 4789.0 4814.6 4818.5 4918.1 4944.8 5054.0 5057.2 5058.5 5060.2 5112.3 4993.3 5002.0 5006.9 5012.4 5053.2 4876.2 4906.2 4908.6 4973.8 5027.9 4276.6 4293.3 4295.3 4298.0 4301.4 3704.1 3716.9 3779.4 3806.1 3809.4 4321.4 4328.0 4334.0 4370.7 4372.5 4997.1 5008.8 5010.8 5014.5 5029.4 4226.8 4262.4 4273.9 4309.4 4314.8 4510.9 4535.9 4543.2 4576.3 4585.0 4678.5 4689.5 4691.6 4697.3 4714.1 4918.8 4936.5 5026.3 5036.0 5073.4 4610.1 4638.4 4649.0 4685.0 4719.6 4930.2 4931.5 4932.1 4938.7 4983.6 5001.6 5002.2 5004.0 5014.9 5046.2 4788.4 4827.4 4829.6 4830.9 4849.4 4881.6 4919.4 4926.3 4926.5 4932.0 4793.0 4803.5 4803.6 4803.8 4846.9 4825.7 4898.4 4901.1 4906.4 4949.3 4697.4 4733.3 4740.2 4757.3 4814.9 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#1] ext.html Cycles:180 Average:42575.57 Median:3518.59 stddev:72474.09 (2059.8%) stddev-sans-first:72608.63 09:33:45 INFO - PROCESS | 4080 | Values: 525.3 526.2 550.3 571.2 584.3 27086.5 36683.6 37976.2 38084.8 41567.2 37504.8 37810.4 37836.4 37856.0 37873.4 7214.1 7532.4 7704.7 7705.1 7714.8 7314.5 9656.1 9873.8 9889.2 10224.9 9958.7 10027.6 10029.0 10032.6 10048.3 9811.8 9834.8 9862.6 9887.7 9891.8 9733.3 9817.0 9817.4 9826.8 9833.3 36505.0 36779.0 36811.6 36811.8 36826.6 3033.2 3150.6 3175.4 3233.1 3360.7 27220.0 28008.3 28254.0 28360.6 28503.0 3495.8 3508.0 3529.2 3585.8 3619.9 169736.0 170019.0 170112.0 170550.0 171386.3 50295.5 51502.2 51561.5 51568.2 51624.7 54447.7 54619.0 54634.5 54650.7 54652.7 11613.3 12094.5 12098.8 12121.1 12125.5 8555.1 8861.1 8877.0 9308.3 9390.8 15667.3 15976.6 16042.9 16085.5 16161.5 5585.3 5710.8 5739.5 5794.2 5818.6 785.0 850.1 881.7 931.5 943.8 25563.6 25693.7 26077.1 26246.3 26987.0 909.5 944.3 970.3 987.0 993.7 13726.8 13952.4 13966.7 14061.3 14154.4 326356.0 328656.0 328722.0 329428.0 329766.0 47012.8 47147.0 47214.0 47223.0 47229.8 1051.0 1093.6 1094.7 1158.2 1163.6 22475.3 29350.7 30012.8 30052.8 30197.8 44333.6 44969.8 45345.8 45387.3 45392.3 299053.0 302358.0 302698.0 303439.0 303786.0 30396.8 31192.2 34890.7 35907.5 38440.4 38589.0 39464.8 39482.6 39879.4 40275.2 32719.7 32974.2 33042.5 33243.8 33259.0 32476.5 33228.5 33451.8 33563.1 33966.3 33227.0 33347.5 33423.7 33472.3 33940.3 27431.7 29489.9 30564.7 30635.5 30808.4 21484.7 22498.9 23243.2 23834.2 23923.2 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#2] jquery.html Cycles:180 Average:23262.75 Median:46516.65 stddev:54816.16 (117.8%) stddev-sans-first:54942.95 09:33:45 INFO - PROCESS | 4080 | Values: 353.9 356.8 357.2 370.2 381.7 14734.1 15597.2 16495.9 16604.0 16628.1 15548.1 15814.4 16305.2 16475.8 16577.6 4469.7 4833.9 4847.7 4858.4 4865.5 3600.6 3778.4 4740.4 4849.9 5339.9 5608.5 5666.7 5749.5 5749.6 5760.0 5654.6 5712.3 5724.8 5833.6 5873.9 5166.5 5167.7 5169.8 5173.2 5174.1 19386.4 20504.3 20674.7 20904.2 20949.7 1893.9 1980.5 1988.5 2072.7 2648.5 13155.8 13728.0 13838.2 14638.5 14852.3 1765.0 1822.7 1858.7 1881.7 1891.9 70612.0 72818.3 74010.0 74189.6 75410.0 15721.0 15921.8 16193.2 16209.4 16402.0 27706.4 27837.3 27885.0 27904.6 27944.4 7840.8 7969.1 8019.6 8247.0 8336.6 6447.2 6510.6 6517.9 6694.5 6735.0 7944.5 8163.0 8174.0 8279.5 8336.4 2540.5 3181.0 3296.2 3349.6 3358.3 701.9 853.0 853.8 894.8 956.4 14129.7 15525.9 15688.0 16354.5 17014.0 802.1 819.7 842.1 842.7 884.1 8540.7 8603.8 8995.9 9031.7 9212.2 325801.0 328891.0 329162.0 331046.0 331692.0 65891.0 76797.0 76894.6 76981.0 79853.0 871.2 901.6 910.1 910.7 911.5 14772.6 15083.9 15093.3 15182.5 15864.6 31708.8 35602.5 35650.6 36537.5 36575.9 46084.5 46948.8 53354.5 53601.7 54368.7 7006.5 7307.8 7363.3 7502.5 7915.3 10855.3 11365.3 11466.6 11921.3 12409.1 5497.8 5653.0 9690.1 10202.7 10500.8 9476.1 10116.5 10265.4 10459.9 10506.1 9475.7 9702.4 9705.6 10174.7 10892.3 6625.7 6637.3 6755.9 6883.4 7096.0 7728.0 10346.7 10350.3 10358.7 10477.6 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#3] mootools.html Cycles:180 Average:4782.33 Median:6008.11 stddev:2638.75 (43.9%) stddev-sans-first:2633.41 09:33:45 INFO - PROCESS | 4080 | Values: 1332.3 1347.3 1400.2 1579.4 1616.6 6578.3 6745.1 7024.8 7479.7 7497.7 6889.4 7263.9 7273.5 7381.5 7468.7 2082.7 2099.7 2116.6 2116.9 2121.4 1771.9 1826.1 2059.4 2085.4 2158.4 1973.2 2036.9 2042.6 2051.8 2057.5 2021.8 2024.4 2033.6 2038.2 2060.7 2057.0 2085.9 2088.8 2089.1 2097.9 7302.0 7338.7 7351.9 7356.8 7358.1 4271.0 4356.3 4359.8 4361.8 4387.1 2640.4 2710.8 2866.1 2873.1 2880.9 859.8 871.4 874.2 1715.1 1746.2 9536.9 9671.6 9682.5 9720.3 9814.9 3740.3 3910.1 3912.1 3913.8 3930.1 7828.1 7834.9 7842.1 7843.6 7850.6 4421.6 4424.5 4429.4 4431.4 4434.8 4000.7 4013.3 4054.6 4066.8 4068.0 3021.9 3022.8 3079.8 3104.3 3114.8 909.4 930.8 934.6 942.3 946.4 680.7 685.5 735.7 742.1 769.0 4764.6 4776.5 4800.0 4927.7 4931.6 801.8 807.0 817.3 822.5 861.2 3271.6 3272.6 3335.5 3355.9 3384.8 8532.6 9085.6 9097.6 9097.7 9106.9 9063.0 9065.1 9073.5 9075.2 9080.2 6796.6 6914.9 6994.4 7004.3 7030.9 4940.4 4957.3 4976.0 4983.9 5072.6 8227.8 8253.5 8258.6 8317.7 8324.3 8442.3 8465.3 8474.7 8507.4 8543.5 5994.1 6052.6 6133.0 6151.1 6151.5 6866.4 6963.2 7004.7 7031.2 7036.9 5543.3 6077.3 6217.4 6232.1 6385.1 6001.2 6020.3 6062.4 6140.3 6190.7 5991.3 6015.0 6026.7 6031.1 6093.8 4433.9 4453.6 4481.3 4495.8 4544.4 4204.2 4280.0 4295.9 4403.0 4564.6 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#4] prototype.html Cycles:180 Average:4081.06 Median:4610.71 stddev:1172.02 (25.4%) stddev-sans-first:1140.82 09:33:45 INFO - PROCESS | 4080 | Values: 320.7 337.2 337.6 341.9 344.8 4601.4 4656.7 4696.6 4731.9 4747.1 4602.1 4675.6 4690.4 4714.4 4773.8 3845.3 3849.8 3854.7 3859.6 3864.1 3847.9 3852.6 3864.7 3865.2 3872.4 3578.1 3667.2 3669.0 3682.0 3701.6 3664.7 3665.4 3668.5 3671.2 3674.6 3176.4 3197.9 3210.8 3234.0 3241.6 4432.4 4598.3 4600.0 4614.8 4620.5 4153.0 4177.5 4183.1 4184.3 4196.6 4955.7 4958.4 4958.4 4958.5 4975.9 3499.8 3519.3 3534.9 3539.6 3542.7 4927.3 5147.6 5147.9 5151.5 5179.0 3552.9 3568.9 3570.8 3582.7 3586.3 3621.9 3630.3 3643.5 3645.7 3648.5 3989.3 4179.6 4185.9 4186.2 4192.2 4967.3 4995.1 5008.7 5012.6 5016.2 1173.0 1177.5 1197.6 1235.9 1243.8 814.3 828.5 838.2 839.7 846.8 4188.9 4235.3 4248.4 4254.0 4271.7 4953.6 4955.9 4966.3 4979.1 4992.3 4303.3 4312.8 4313.6 4319.8 4325.7 2529.6 2655.2 2655.2 2696.6 2699.8 4500.8 4562.4 4606.6 4664.2 4715.1 5062.5 5080.6 5099.0 5106.3 5108.4 4503.9 4577.7 4621.2 4636.5 4669.0 4984.7 5010.4 5010.9 5011.4 5031.7 4850.9 4864.7 4887.6 4899.9 4921.6 4489.7 4632.8 4647.8 4650.7 4663.3 3697.5 3715.6 3715.9 3726.4 3734.7 4921.3 4924.3 4975.6 4984.9 4987.5 4618.8 4743.6 4887.8 4965.8 4983.6 4954.7 4958.3 4968.7 4970.7 4972.3 4762.2 4898.2 4900.4 4903.7 4906.2 4963.0 4980.0 4996.6 4998.4 4999.8 4859.7 4868.9 4872.5 4875.2 4879.9 09:33:45 INFO - PROCESS | 4080 | 09:33:45 INFO - PROCESS | 4080 | [#5] yui.html Cycles:180 Average:13747.79 Median:22586.11 stddev:16613.23 (73.6%) stddev-sans-first:16630.61 09:33:45 INFO - PROCESS | 4080 | Values: 625.2 658.1 660.0 662.4 726.1 12238.1 13437.9 13438.7 13452.9 13509.1 12971.4 12977.4 12978.5 12978.7 12992.2 619.5 639.1 761.1 762.2 775.6 771.0 781.2 785.7 785.7 785.7 816.5 822.3 822.6 826.2 828.4 645.7 665.7 789.6 817.4 817.9 593.4 593.7 594.1 594.3 595.0 12804.6 12820.7 12823.6 12827.5 12845.9 12168.9 12286.3 12371.3 12650.1 12658.6 11076.7 11101.8 11229.1 11440.5 11595.8 3941.9 3975.1 4011.2 4069.8 4166.4 60233.0 60771.7 62859.0 63259.4 63755.0 9915.4 9925.4 10045.8 10109.6 10370.4 17190.2 17229.9 17271.2 17400.4 17487.6 6261.7 6320.7 6348.5 6365.8 6513.4 3376.9 3387.1 3403.2 3466.8 3562.8 2668.4 2722.7 2742.8 2743.8 2744.9 459.1 461.5 464.1 479.0 482.0 441.7 453.4 525.5 535.5 565.2 7509.3 7605.3 7705.5 7727.3 7783.4 520.5 548.6 568.8 586.3 610.6 3481.5 3714.9 3814.4 3864.1 3934.9 48813.3 49881.5 59119.0 61281.4 61549.4 51586.7 51833.2 52785.0 53517.2 53743.7 47404.5 47714.3 48285.0 48514.8 50671.7 8378.3 8629.0 8915.3 8966.8 9001.0 30813.8 31206.0 31431.7 34728.5 34744.8 19995.7 21920.7 31050.2 43219.8 44730.6 9583.9 10077.8 10517.3 10999.5 11091.1 11544.8 11750.5 11812.8 11954.2 12100.7 11874.9 11993.8 12125.8 12195.0 12548.1 11690.4 12040.4 12070.2 12225.4 12253.6 11151.3 11596.2 11744.6 11771.4 11917.9 9769.9 10014.5 10301.5 10455.6 10556.1 8972.7 9121.5 9643.9 9965.1 9979.4 09:33:45 INFO - PROCESS | 4080 | -------- Summary: end -------- 09:33:45 INFO - PROCESS | 4080 | 09:33:46 INFO - TEST-INFO | 4080: exit 0 09:33:46 INFO - TEST-OK | dromaeo_css | took 1127489ms 09:33:46 INFO - TEST-START | kraken 09:33:46 INFO - operating with platform_type : linux_ 09:33:46 INFO - Initialising browser for kraken test... 09:33:46 INFO - TEST-INFO | started process 4388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeL4w3t/profile http://localhost:60107/getInfo.html) 09:33:51 INFO - PROCESS | 4388 | __metrics Screen width/height:1600/1200 09:33:51 INFO - PROCESS | 4388 | colorDepth:24 09:33:51 INFO - PROCESS | 4388 | Browser inner width/height: 1024/697 09:33:51 INFO - PROCESS | 4388 | __metrics 09:33:52 INFO - PROCESS | 4388 | 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" 09:33:52 INFO - PROCESS | 4388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:53 INFO - PROCESS | 4388 | console.error: PushService: 09:33:53 INFO - PROCESS | 4388 | stateChangeProcessEnqueue: Error transitioning state 09:33:53 INFO - PROCESS | 4388 | UnknownError 09:33:53 INFO - TEST-INFO | 4388: exit 0 09:33:53 INFO - Browser initialized. 09:33:53 INFO - Running cycle 1/1 for kraken test... 09:33:53 INFO - TEST-INFO | started process 4470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeL4w3t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:06 INFO - PROCESS | 4470 | RSS: Main: 175878144 09:34:06 INFO - PROCESS | 4470 | 09:34:08 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60107/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:09 INFO - PROCESS | 4470 | RSS: Main: 239136768 09:34:09 INFO - PROCESS | 4470 | 09:34:12 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60107/tests/kraken/driver.html?testName=audio-dft) 09:34:13 INFO - PROCESS | 4470 | RSS: Main: 217800704 09:34:13 INFO - PROCESS | 4470 | 09:34:16 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60107/tests/kraken/driver.html?testName=audio-fft) 09:34:17 INFO - PROCESS | 4470 | RSS: Main: 300326912 09:34:17 INFO - PROCESS | 4470 | 09:34:19 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60107/tests/kraken/driver.html?testName=audio-oscillator) 09:34:19 INFO - PROCESS | 4470 | RSS: Main: 211628032 09:34:19 INFO - PROCESS | 4470 | 09:34:22 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:34:22 INFO - PROCESS | 4470 | RSS: Main: 215183360 09:34:22 INFO - PROCESS | 4470 | 09:34:25 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60107/tests/kraken/driver.html?testName=imaging-darkroom) 09:34:26 INFO - PROCESS | 4470 | RSS: Main: 253947904 09:34:26 INFO - PROCESS | 4470 | 09:34:30 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60107/tests/kraken/driver.html?testName=imaging-desaturate) 09:34:30 INFO - PROCESS | 4470 | RSS: Main: 254173184 09:34:30 INFO - PROCESS | 4470 | 09:34:33 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60107/tests/kraken/driver.html?testName=json-parse-financial) 09:34:34 INFO - PROCESS | 4470 | RSS: Main: 252723200 09:34:34 INFO - PROCESS | 4470 | 09:34:35 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:34:36 INFO - PROCESS | 4470 | RSS: Main: 214597632 09:34:36 INFO - PROCESS | 4470 | 09:34:38 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:34:39 INFO - PROCESS | 4470 | RSS: Main: 222613504 09:34:39 INFO - PROCESS | 4470 | 09:34:41 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:34:42 INFO - PROCESS | 4470 | RSS: Main: 226304000 09:34:42 INFO - PROCESS | 4470 | 09:34:44 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:34:45 INFO - PROCESS | 4470 | RSS: Main: 224870400 09:34:45 INFO - PROCESS | 4470 | 09:34:48 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:34:49 INFO - PROCESS | 4470 | RSS: Main: 227299328 09:34:49 INFO - PROCESS | 4470 | 09:34:50 INFO - PROCESS | 4470 | Cycle 1(1): loaded http://localhost:60107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60107/tests/kraken/driver.html?testName=ai-astar) 09:34:51 INFO - PROCESS | 4470 | RSS: Main: 281608192 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | __start_tp_report 09:34:51 INFO - PROCESS | 4470 | _x_x_mozilla_page_load 09:34:51 INFO - PROCESS | 4470 | _x_x_mozilla_page_load_details 09:34:51 INFO - PROCESS | 4470 | |i|pagename|runs| 09:34:51 INFO - PROCESS | 4470 | |0;ai-astar;116;115;116;118;116;116;116;115;115;115 09:34:51 INFO - PROCESS | 4470 | |1;audio-beat-detection;150;132;138;170;138;128;140;134;162;132 09:34:51 INFO - PROCESS | 4470 | |2;audio-dft;161;161;162;162;162;160;161;160;158;159 09:34:51 INFO - PROCESS | 4470 | |3;audio-fft;76;83;77;118;78;78;79;80;107;76 09:34:51 INFO - PROCESS | 4470 | |4;audio-oscillator;84;86;118;85;85;85;105;85;85;121 09:34:51 INFO - PROCESS | 4470 | |5;imaging-gaussian-blur;127;128;127;126;126;128;127;127;127;127 09:34:51 INFO - PROCESS | 4470 | |6;imaging-darkroom;146;147;146;146;146;147;146;147;146;146 09:34:51 INFO - PROCESS | 4470 | |7;imaging-desaturate;96;95;95;95;96;95;94;95;95;95 09:34:51 INFO - PROCESS | 4470 | |8;json-parse-financial;65;64;66;65;66;66;64;65;65;65 09:34:51 INFO - PROCESS | 4470 | |9;json-stringify-tinderbox;65;65;66;65;66;66;66;66;67;66 09:34:51 INFO - PROCESS | 4470 | |10;stanford-crypto-aes;89;90;88;88;95;90;90;90;118;91 09:34:51 INFO - PROCESS | 4470 | |11;stanford-crypto-ccm;145;141;141;141;141;140;137;139;139;144 09:34:51 INFO - PROCESS | 4470 | |12;stanford-crypto-pbkdf2;182;179;179;183;186;181;180;181;189;181 09:34:51 INFO - PROCESS | 4470 | |13;stanford-crypto-sha256-iterative;71;69;70;68;68;74;73;74;68;68 09:34:51 INFO - PROCESS | 4470 | __end_tp_report 09:34:51 INFO - PROCESS | 4470 | __start_cc_report 09:34:51 INFO - PROCESS | 4470 | _x_x_mozilla_cycle_collect,1934 09:34:51 INFO - PROCESS | 4470 | __end_cc_report 09:34:51 INFO - PROCESS | 4470 | __startTimestamp1481391291251__endTimestamp 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | ------- Summary: start ------- 09:34:51 INFO - PROCESS | 4470 | Number of tests: 14 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 09:34:51 INFO - PROCESS | 4470 | Values: 116.0 115.0 116.0 118.0 116.0 116.0 116.0 115.0 115.0 115.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:139.00 stddev:13.91 (10.0%) stddev-sans-first:14.48 09:34:51 INFO - PROCESS | 4470 | Values: 150.0 132.0 138.0 170.0 138.0 128.0 140.0 134.0 162.0 132.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 09:34:51 INFO - PROCESS | 4470 | Values: 161.0 161.0 162.0 162.0 162.0 160.0 161.0 160.0 158.0 159.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#3] audio-fft Cycles:10 Average:85.20 Median:78.00 stddev:14.76 (18.9%) stddev-sans-first:15.28 09:34:51 INFO - PROCESS | 4470 | Values: 76.0 83.0 77.0 118.0 78.0 78.0 79.0 80.0 107.0 76.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:85.00 stddev:14.89 (17.5%) stddev-sans-first:15.35 09:34:51 INFO - PROCESS | 4470 | Values: 84.0 86.0 118.0 85.0 85.0 85.0 105.0 85.0 85.0 121.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#5] imaging-gaussian-blur Cycles:10 Average:127.00 Median:127.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:34:51 INFO - PROCESS | 4470 | Values: 127.0 128.0 127.0 126.0 126.0 128.0 127.0 127.0 127.0 127.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:34:51 INFO - PROCESS | 4470 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:34:51 INFO - PROCESS | 4470 | Values: 96.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 09:34:51 INFO - PROCESS | 4470 | Values: 65.0 64.0 66.0 65.0 66.0 66.0 64.0 65.0 65.0 65.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 09:34:51 INFO - PROCESS | 4470 | Values: 65.0 65.0 66.0 65.0 66.0 66.0 66.0 66.0 67.0 66.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:90.00 stddev:9.04 (10.0%) stddev-sans-first:9.47 09:34:51 INFO - PROCESS | 4470 | Values: 89.0 90.0 88.0 88.0 95.0 90.0 90.0 90.0 118.0 91.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:141.00 stddev:2.35 (1.7%) stddev-sans-first:1.94 09:34:51 INFO - PROCESS | 4470 | Values: 145.0 141.0 141.0 141.0 141.0 140.0 137.0 139.0 139.0 144.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:181.50 stddev:3.18 (1.8%) stddev-sans-first:3.37 09:34:51 INFO - PROCESS | 4470 | Values: 182.0 179.0 179.0 183.0 186.0 181.0 180.0 181.0 189.0 181.0 09:34:51 INFO - PROCESS | 4470 | 09:34:51 INFO - PROCESS | 4470 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:2.54 (3.6%) stddev-sans-first:2.68 09:34:51 INFO - PROCESS | 4470 | Values: 71.0 69.0 70.0 68.0 68.0 74.0 73.0 74.0 68.0 68.0 09:34:51 INFO - PROCESS | 4470 | -------- Summary: end -------- 09:34:51 INFO - PROCESS | 4470 | 09:34:52 INFO - TEST-INFO | 4470: exit 0 09:34:52 INFO - TEST-OK | kraken | took 66084ms 09:34:52 INFO - SUITE-END | took 1193s 09:34:52 INFO - Completed test suite (00:19:53) 09:34:52 INFO - javascript benchmark 09:34:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2560.5391029614675, 2588.2105957682925, 2615.803814713897, 2625.0, 2633.2758491414847, 4606.792694328755, 4654.022655242399, 4662.8601141965755, 4664.953350466494, 4681.438227412711, 4660.813567457298, 4661.091087237998, 4685.882852928678, 4704.0356726871, 4706.22347312693, 4270.829166833323, 4281.678874084456, 4318.169452289911, 4321.6326612238, 4390.078083602447, 4004.3712058847536, 4388.166248412791, 4402.669799765014, 4413.3379993001145, 4461.7992190351515, 4198.6431153352005, 4222.472190976128, 4379.1022840317655, 4432.534583868712, 4482.596566309032, 4239.894002649928, 4273.465816772903, 4277.914441711148, 4278.122984788142, 4282.379055037002, 4245.936310955338, 4255.680823938186, 4255.744655320691, 4256.7445953242905, 4259.084296876155, 4582.702124361906, 4603.0, 4609.03210325832, 4610.792514336863, 4613.008203236287, 4872.6832755870755, 4927.43334516528, 4971.353724015855, 4979.0788704089855, 5018.372703412074, 5070.340855688737, 5097.0, 5101.821436249714, 5116.334876466036, 5118.411382691001, 4710.905781884343, 4711.081339138904, 4715.599174070208, 4715.622750180046, 4758.928616070762, 5078.822241221539, 5174.456682048406, 5178.844634660966, 5184.611154163453, 5240.318758561362, 4789.042191561702, 4814.614830813526, 4818.518148185153, 4918.139325618003, 4944.752762361867, 5054.0, 5057.190849464068, 5058.46886076964, 5060.190369540929, 5112.335396398445, 4993.300937868701, 5002.049610573979, 5006.899862002812, 5012.423571289275, 5053.2420136979745, 4876.195427754381, 4906.239532872411, 4908.631852611068, 4973.825916092919, 5027.924581131286, 4276.615104640535, 4293.2701440755145, 4295.312749959991, 4297.978510107429, 4301.44081269433, 3704.073981504624, 3716.851325946932, 3779.433085037266, 3806.1245913440066, 3809.371453710107, 4321.395004699345, 4328.026194106301, 4334.0248444099825, 4370.672199585044, 4372.453443319585, 4997.075541024922, 5008.774605142778, 5010.824621138243, 5014.498550144956, 5029.421616514074, 4226.788660566959, 4262.403263543107, 4273.87178384649, 4309.353596960418, 4314.805833737427, 4510.932336014962, 4535.909281814345, 4543.182227199136, 4576.26779715244, 4584.977075114603, 4678.532146785362, 4689.507601701772, 4691.601213896721, 4697.342372067981, 4714.127886341038, 4918.754062296943, 4936.531029552187, 5026.3214466048075, 5035.974820125876, 5073.365829271341, 4610.147122782162, 4638.373819534455, 4649.0469453761225, 4684.976575117103, 4719.551642593948, 4930.186519224288, 4931.50684931504, 4932.087563800708, 4938.703677779345, 4983.551480366785, 5001.57486613642, 5002.199648056294, 5004.049230646166, 5014.899702006012, 5046.243063540498, 4788.401449818773, 4827.396575428072, 4829.613630909448, 4830.903381932412, 4849.393825771778, 4881.560659540658, 4919.3604831371695, 4926.285688575065, 4926.5073492650445, 4931.950680493149, 4793.041391721739, 4803.519648035169, 4803.639727020418, 4803.759812009455, 4846.878828029327, 4825.686330388514, 4898.436679781871, 4901.068796928572, 4906.411230652345, 4949.30709700635, 4697.436307643105, 4733.290006499053, 4740.19416699155, 4757.3101900224965, 4814.8796280093275], "value": 4594.582884583647, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.3459442993474, 526.2448386565277, 550.3379793025067, 571.240250466879, 584.2667452347306, 27086.458270834595, 36683.63316366835, 37976.240475190476, 38084.80957595215, 41567.16865662693, 37504.81247593758, 37810.43284350737, 37836.43245351315, 37856.0, 37873.43189852148, 7214.06217191767, 7532.4350673699555, 7704.6918123275, 7705.075390953093, 7714.845703085963, 7314.451416143784, 9656.082672146134, 9873.753156171117, 9889.208863290956, 10224.897751022469, 9958.701238962843, 10027.64903228387, 10029.0, 10032.59869605215, 10048.346857454279, 9811.754706132333, 9834.754131146707, 9862.556184971621, 9887.703368898945, 9891.752706182331, 9733.323336082563, 9816.950915245377, 9817.410955342631, 9826.80346393068, 9833.31166818323, 36505.0, 36779.0, 36811.63188368109, 36811.815940920394, 36826.63173368286, 3033.211365045082, 3150.6376766671897, 3175.42842288388, 3233.143217047484, 3360.663933606644, 27220.0, 28008.299792505146, 28254.0, 28360.574591381144, 28503.0, 3495.755297129202, 3508.0, 3529.170644898447, 3585.8027808470524, 3619.8826064343457, 169736.0, 170019.0, 170112.0, 170550.0, 171386.28761705753, 50295.49704502908, 51502.22746658803, 51561.48438515642, 51568.22647660288, 51624.741876290376, 54447.72776136173, 54619.0, 54634.45365546373, 54650.72674636601, 54652.726736366065, 11613.30320180775, 12094.455749491293, 12098.81851772224, 12121.090918181171, 12125.514979400725, 8555.144485551395, 8861.113888611088, 8876.955615222012, 9308.255339572817, 9390.765230869283, 15667.2949717263, 15976.600584985235, 16042.919785400998, 16085.517434476984, 16161.515154545383, 5585.273914391184, 5710.800121995793, 5739.540836733051, 5794.188813566104, 5818.592698511064, 784.9764507064797, 850.0734199722209, 881.6517475597232, 931.5295775633261, 943.7593413679605, 25563.61654575183, 25693.743062569134, 26077.087301944342, 26246.34384140412, 26987.0, 909.5134103254727, 944.3059351376733, 970.3256236915418, 986.980260394788, 993.7167907146573, 13726.794098088536, 13952.441902323793, 13966.720665586834, 14061.296935153201, 14154.36305366261, 326356.0, 328656.0, 328722.0, 329428.0, 329766.0, 47012.7649361751, 47147.0, 47214.0, 47223.0, 47229.76385118053, 1050.9632162874416, 1093.5625749700184, 1094.7372630568766, 1158.1661203933154, 1163.5520324674892, 22475.325740227167, 29350.706492934798, 30012.849935750182, 30052.84973575118, 30197.849010754806, 44333.556664432945, 44969.775151124035, 45345.77327113343, 45387.319190211514, 45392.31911521264, 299053.0, 302358.0, 302698.0, 303439.0, 303786.0, 30396.84801575978, 31192.22019449532, 34890.65109348975, 35907.46138807868, 38440.42339364856, 38589.035274118374, 39464.80267598644, 39482.60517394904, 39879.40180897231, 40275.1944961105, 32719.672803272613, 32974.17564560905, 33042.504362434105, 33243.833780830944, 33259.0, 32476.512852307707, 33228.50157247595, 33451.83274083614, 33563.13823163138, 33966.32067358688, 33227.00318990434, 33347.49978750369, 33423.665763342055, 33472.318045014545, 33940.321193576485, 27431.725682742916, 29489.88185111577, 30564.694353057075, 30635.54046689346, 30808.383832323678, 21484.67772983375, 22498.88750556237, 23243.186488472824, 23834.16580419677, 23923.16268930579], "value": 17011.324772535034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8637624514561, 356.80554098016586, 357.1802712774183, 370.1689706608664, 381.6946442845723, 14734.115953042825, 15597.220138993047, 16495.917520412397, 16604.0, 16628.085455299955, 15548.06711597305, 15814.4464943727, 16305.184740762974, 16475.83524164758, 16577.585560360996, 4469.687121901468, 4833.879153021167, 4847.733374664392, 4858.441279252878, 4865.513448655124, 3600.6039335673054, 3778.433235014756, 4740.431148262213, 4849.903001939959, 5339.866503337428, 5608.523275521586, 5666.688332141731, 5749.511291540203, 5749.59752817303, 5760.0, 5654.6041777075825, 5712.2574065371655, 5724.771009159628, 5833.620814647077, 5873.882522349571, 5166.483351664841, 5167.664101833407, 5169.8449046528285, 5173.198154286091, 5174.09453345663, 19386.4184074478, 20504.257138143275, 20674.689879651665, 20904.163833446644, 20949.68575471369, 1893.933712320062, 1980.4553747719349, 1988.4730546405215, 2072.7098206251135, 2648.4835457085874, 13155.802662960063, 13728.0, 13838.169709817343, 14638.487652932203, 14852.344240876884, 1764.9647007059787, 1822.7174787907895, 1858.6654402207598, 1881.6801143805585, 1891.8864868107958, 70612.0, 72818.27181728168, 74010.0, 74189.62905185494, 75410.0, 15721.0, 15921.840781592267, 16193.190340483165, 16209.351625935067, 16402.0, 27706.445871082466, 27837.304067398476, 27885.0, 27904.581431278548, 27944.44111117807, 7840.803979900434, 7969.083555391204, 8019.558924259221, 8246.958765206136, 8336.583170841555, 6447.1618689571, 6510.576812507172, 6517.902231466532, 6694.464442844561, 6735.0, 7944.483608565425, 8163.0, 8173.959130204311, 8279.50322980623, 8336.416450848503, 2540.479201763634, 3181.0, 3296.208909861664, 3349.648286929886, 3358.294758100826, 701.9122609673791, 853.0337571896837, 853.7737499562621, 894.8031433084841, 956.4022485946283, 14129.717405651829, 15525.922370388076, 15688.0, 16354.509364719077, 17014.0, 802.12968928713, 819.6516480495766, 842.1241908415302, 842.726114012936, 884.12471653063, 8540.70107546233, 8603.784905377415, 8995.865062024075, 9031.683891063782, 9212.216961558208, 325801.0, 328891.0, 329162.0, 331046.0, 331692.0, 65891.0, 76797.0, 76894.615526922, 76981.0, 79853.0, 871.215905684876, 901.5717534171216, 910.0899100898969, 910.717677519971, 911.49867572836, 14772.63068423298, 15083.924580377028, 15093.320800564221, 15182.544523664308, 15864.60338491547, 31708.84145579257, 35602.46596301006, 35650.643493564734, 36537.45193822148, 36575.86382570624, 46084.53915460803, 46948.7652561735, 53354.4664553365, 53601.731991339795, 54368.728156358964, 7006.5095443320515, 7307.817304567428, 7363.337299643058, 7502.474826762293, 7915.287624113857, 10855.294405863593, 11365.261258018203, 11466.598669046543, 11921.284722916653, 12409.069319801232, 5497.780088796403, 5653.0, 9690.079442452956, 10202.744931376776, 10500.842487362543, 9476.14714675655, 10116.494175291353, 10265.435401053013, 10459.895401045893, 10506.106980906708, 9475.715728528156, 9702.417854928726, 9705.563249653642, 10174.74563135898, 10892.292001019909, 6625.734970601122, 6637.336266373517, 6755.864882702417, 6883.380495755406, 7095.9645201773665, 7728.0, 10346.741331466774, 10350.275480716278, 10358.741031474274, 10477.580896764044], "value": 8636.987511299021, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.2872263339113, 1347.299404309759, 1400.2018849255928, 1579.368252698921, 1616.6443382455864, 6578.276389597152, 6745.055692203083, 7024.789256322312, 7479.738209162673, 7497.737579184723, 6889.448844092473, 7263.927360726391, 7273.490855640107, 7381.520201186932, 7468.701251949929, 2082.6771850363216, 2099.737532808399, 2116.5766846630736, 2116.9259075932346, 2121.395402310342, 1771.8848274862157, 1826.0960824391946, 2059.41379054037, 2085.4265077103764, 2158.417227348615, 1973.1614064022804, 2036.9490762730902, 2042.5710600773855, 2051.8050785175383, 2057.537054162809, 2021.7877122902105, 2024.423039433769, 2033.613613413454, 2038.1745393115812, 2060.6702927531674, 2056.979430205694, 2085.8644188127723, 2088.822450091744, 2089.1330098009307, 2097.9370618881458, 7301.963490182515, 7338.669759860819, 7351.926480735177, 7356.77929662111, 7358.117025956866, 4270.978645106786, 4356.302991521341, 4359.825606975717, 4361.8441113105055, 4387.056782791715, 2640.366312085105, 2710.7966902482194, 2866.125831621355, 2873.0806142034558, 2880.8825513247957, 859.8194379180379, 871.4117970369975, 874.2437791310471, 1715.133857402004, 1746.2142036083685, 9536.904630953672, 9671.564779584865, 9682.515874206332, 9720.319577629569, 9814.90185098147, 3740.345439548068, 3910.1006768443417, 3912.0611053346993, 3913.7847418391693, 3930.0960779020415, 7828.060632723996, 7834.921650783419, 7842.098158711707, 7843.568603726736, 7850.646720897574, 4421.579949904753, 4424.513303536608, 4429.379886815848, 4431.423914891044, 4434.778261086933, 4000.7199496035, 4013.297672907288, 4054.594540545922, 4066.8169932353117, 4067.983004248938, 3021.8942337018098, 3022.833744144093, 3079.8306093164642, 3104.3170502489415, 3114.8442577871015, 909.4270609515962, 930.8417569013149, 934.5654270764128, 942.3356533643902, 946.3990366117565, 680.669875110571, 685.4584877946467, 735.724103461202, 742.0687037767567, 768.9692412303557, 4764.571188593043, 4776.450708168605, 4800.0, 4927.704337739747, 4931.5561599455505, 801.7674874286508, 806.9717559885376, 817.3257062922993, 822.5434883639638, 861.1517655109712, 3271.6401195868434, 3272.6236482804784, 3335.533025376449, 3355.9496607550905, 3384.830758462067, 8532.616032278562, 9085.636574536944, 9097.590608422637, 9097.727068187965, 9106.86339704905, 9062.954685226663, 9065.138811812867, 9073.500957447404, 9075.2286055686, 9080.22818060459, 6796.626185559743, 6914.896276555855, 6994.405475534535, 7004.2645522220455, 7030.859382812315, 4940.431850337257, 4957.281194226889, 4976.0, 4983.900321993612, 5072.565168719109, 8227.83544329122, 8253.546054967035, 8258.587070646563, 8317.667293308199, 8324.334053275845, 8442.282405995558, 8465.28045116172, 8474.703385381483, 8507.361947853811, 8543.530105844238, 5994.10088486713, 6052.636841789506, 6132.969335153296, 6151.0773383992755, 6151.477124444473, 6866.416354609914, 6963.2340442552295, 7004.719811207495, 7031.226565077735, 7036.8592628146125, 5543.334799824047, 6077.3011103721865, 6217.378262173927, 6232.096346029891, 6385.106085148176, 6001.2498437695285, 6020.337762845996, 6062.36345183767, 6140.3245642978345, 6190.690465476618, 5991.2510936132985, 6014.969925150346, 6026.7287972040485, 6031.09533569968, 6093.817185484442, 4433.911321773482, 4453.55464453552, 4481.260592002283, 4495.820167193407, 4544.386507821402, 4204.1801848639225, 4280.0, 4295.892602684958, 4402.9559704402545, 4564.589186973189], "value": 3877.7548675073754, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.7289840085129, 337.20924432206476, 337.55779928293936, 341.882050692511, 344.77761843610864, 4601.446197360464, 4656.719402164402, 4696.624270058395, 4731.888006318512, 4747.145513807513, 4602.148602508539, 4675.602573781224, 4690.366800481934, 4714.4106986626675, 4773.80904763809, 3845.3078445879732, 3849.845046486057, 3854.6530812226824, 3859.5561510426237, 3864.1305706216017, 3847.8845634630866, 3852.595477474867, 3864.7101467389916, 3865.246276975991, 3872.4384964180176, 3578.14124610093, 3667.174885650734, 3668.963310366902, 3681.9815900920594, 3701.555813302394, 3664.670179683841, 3665.3505922335057, 3668.504751858505, 3671.229041901204, 3674.6325367463305, 3176.3806057818733, 3197.936041279185, 3210.811999560154, 3233.96766032339, 3241.594800649919, 4432.423784907974, 4598.287265473857, 4599.977000115012, 4614.792334344963, 4620.475243169746, 4152.979235103835, 4177.4778152730905, 4183.142455796585, 4184.330507118846, 4196.580341965779, 4955.653104282702, 4958.380202474691, 4958.380202474691, 4958.504149585049, 4975.850724478271, 3499.8275577681507, 3519.2961407718303, 3534.8586056557706, 3539.5752509698746, 3542.734294927891, 4927.334809800703, 5147.588192944518, 5147.897042059175, 5151.5363617274625, 5179.0, 3552.946705799415, 3568.91077723059, 3570.8214589270433, 3582.7492075554983, 3586.30067136911, 3621.909452263662, 3630.3102410541915, 3643.4899114123514, 3645.708343332527, 3648.4892115103908, 3989.3218152914073, 4179.561146079618, 4185.874423767292, 4186.162767446523, 4192.161567686475, 4967.3045773591, 4995.075910956485, 5008.6744361616375, 5012.624053195952, 5016.222485514678, 1172.9589464368705, 1177.4701384376892, 1197.6167626359481, 1235.8887700106854, 1243.7823380908485, 814.3159745813545, 828.5153185386496, 838.2288294768828, 839.7312859884901, 846.7629063862129, 4188.937165942513, 4235.25882970479, 4248.4477017987465, 4254.021575037758, 4271.722338048017, 4953.554180123806, 4955.860152165023, 4966.279889416087, 4979.053979743837, 4992.2761199626575, 4303.33298338758, 4312.849050283287, 4313.61177494027, 4319.827206911751, 4325.653947684178, 2529.6205569164404, 2655.150351887416, 2655.221001928712, 2696.5955106734145, 2699.784017278613, 4500.842470513517, 4562.361269422284, 4606.631469482433, 4664.230401983703, 4715.0805592909055, 5062.544371006554, 5080.628230377777, 5099.0, 5106.259592359087, 5108.386993560796, 4503.909921801545, 4577.679562430598, 4621.214393553104, 4636.489986101594, 4668.953310466852, 4984.7258400788305, 5010.448850626355, 5010.888893328857, 5011.4236862761245, 5031.6729412588065, 4850.878728031827, 4864.659473836798, 4887.584555312838, 4899.926501102415, 4921.557059864629, 4489.720429677526, 4632.8146874126005, 4647.8140874366, 4650.651201159859, 4663.300504924288, 3697.5193224880595, 3715.554133503997, 3715.866660668414, 3726.4037753959237, 3734.738568300193, 4921.26181072842, 4924.335214745964, 4975.626827987988, 4984.925226121539, 4987.501249874984, 4618.769061546842, 4743.596794272387, 4887.804487820447, 4965.776540055634, 4983.551480366785, 4954.65317427791, 4958.305837182869, 4968.726720030433, 4970.676906001098, 4972.328735620789, 4762.214234651244, 4898.21628539432, 4900.411950565955, 4903.656744027884, 4906.239532872411, 4963.0, 4979.975100124476, 4996.60027197816, 4998.425181104074, 4999.825041115408, 4859.684120532154, 4868.878278043077, 4872.537108974641, 4875.24433712775, 4879.926416188433], "value": 3717.940535198151, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.2434554189431, 658.1049772309658, 659.9538032337737, 662.3674390956637, 726.0778810910142, 12238.143329966906, 13437.865621343784, 13438.664033399169, 13452.865471345283, 13509.054366194357, 12971.416286267116, 12977.351132443364, 12978.54575089872, 12978.675533111676, 12992.220466771976, 619.4889216396468, 639.063771574643, 761.1247065874234, 762.1844626249915, 775.5850619918344, 771.0285040848543, 781.1953687701676, 785.6857257097149, 785.7132146766446, 785.7249962513132, 816.4774544291658, 822.2928281677753, 822.5804839531852, 826.2481142160635, 828.4283844147549, 645.7126578672493, 665.7436886798569, 789.6091434739841, 817.370624619046, 817.889584906036, 593.3562085137631, 593.7031484257872, 594.0880748051763, 594.3432507079701, 594.9974293315777, 12804.61586152417, 12820.743585128339, 12823.61529154127, 12827.486900524062, 12845.935770321183, 12168.878311216864, 12286.262824230485, 12371.319577423239, 12650.114491985565, 12658.55695050678, 11076.667699969013, 11101.777964440747, 11229.101671866312, 11440.542378304857, 11595.768084638345, 3941.921161576752, 3975.1254723960683, 4011.1576568920564, 4069.837206511736, 4166.375043743433, 60233.0, 60771.69614151901, 62859.0, 63259.36740632581, 63755.0, 9915.355501892354, 9925.404475731479, 10045.79908401828, 10109.595616175271, 10370.429626370622, 17190.226439810238, 17229.91385043067, 17271.222794974005, 17400.39098631542, 17487.562810929576, 6261.749530018748, 6320.652364120017, 6348.460380867678, 6365.777197798055, 6513.446357059608, 3376.9493457598155, 3387.085486918518, 3403.2002479417315, 3466.791992480459, 3562.8040457775073, 2668.3862711576444, 2722.68689100752, 2742.8491432970977, 2743.8079334446397, 2744.8627568621887, 459.1459884614614, 461.5107985535343, 464.1204916682934, 479.00606242047195, 481.95421434963623, 441.6599218601723, 453.36981595582165, 525.4561528817732, 535.5287347134574, 565.1579147070835, 7509.324160825552, 7605.315521603082, 7705.460617756816, 7727.343175830118, 7783.4162437817395, 520.5471240021205, 548.6132276744885, 568.7582777319623, 586.3345103307777, 610.575649923299, 3481.5473988381855, 3714.9442758358646, 3814.351560234711, 3864.091938394475, 3934.940975885364, 48813.26780098301, 49881.50118498841, 59119.0, 61281.38718612846, 61549.384506155264, 51586.74206628943, 51833.2225016625, 52785.0, 53517.1972420414, 53743.731281344124, 47404.525954740704, 47714.28428573574, 48284.999999999294, 48514.75742621335, 50671.74664126656, 8378.329733621174, 8628.956855215934, 8915.331350148635, 8966.775830604287, 9000.954995224982, 30813.845930770203, 31206.0, 31431.685683142874, 34728.479072813425, 34744.82627586846, 19995.700064498753, 21920.671189931843, 31050.223744406572, 43219.78390108155, 44730.55269447264, 9583.856242156513, 10077.798444031225, 10517.263791534826, 10999.505022274177, 11091.112710982941, 11544.826827597424, 11750.536525889624, 11812.76374472523, 11954.163208575315, 12100.697482563006, 11874.881251187378, 11993.76012479728, 12125.81811272814, 12195.0, 12548.05889558305, 11690.357030363411, 12040.397980100784, 12070.215435996632, 12225.388730563258, 12253.632391028283, 11151.330920144817, 11596.188266821242, 11744.58893938708, 11771.352575608082, 11917.940410297893, 9769.85345219808, 10014.499275036365, 10301.536430860479, 10455.581776728846, 10556.102731267621, 8972.685955991508, 9121.45271283725, 9643.855342170013, 9965.053319934595, 9979.401235925867], "value": 6019.197655386165, "unit": "score"}], "name": "dromaeo_css", "value": 6232.134902725286, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 118.0, 116.0, 116.0, 116.0, 115.0, 115.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 138.0, 170.0, 138.0, 128.0, 140.0, 134.0, 162.0, 132.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 160.0, 161.0, 160.0, 158.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 77.0, 118.0, 78.0, 78.0, 79.0, 80.0, 107.0, 76.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 118.0, 85.0, 85.0, 85.0, 105.0, 85.0, 85.0, 121.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 127.0, 126.0, 126.0, 128.0, 127.0, 127.0, 127.0, 127.0], "value": 127.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 65.0, 66.0, 66.0, 64.0, 65.0, 65.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 88.0, 95.0, 90.0, 90.0, 90.0, 118.0, 91.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 141.0, 141.0, 141.0, 141.0, 140.0, 137.0, 139.0, 139.0, 144.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 179.0, 179.0, 183.0, 186.0, 181.0, 180.0, 181.0, 189.0, 181.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 68.0, 68.0, 74.0, 73.0, 74.0, 68.0, 68.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1583.3, "alertThreshold": 2.0}]} 09:34:53 INFO - Return code: 0 09:34:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:34:53 INFO - # TBPL SUCCESS # 09:34:53 INFO - Running post-action listener: _resource_record_post_action 09:34:53 INFO - [mozharness: 2016-12-10 17:34:53.069809Z] Finished run-tests step (success) 09:34:53 INFO - Running post-run listener: _resource_record_post_run 09:34:53 INFO - Total resource usage - Wall time: 1210s; CPU: 13.0%; Read bytes: 425984; Write bytes: 399482880; Read time: 48; Write time: 465600 09:34:53 INFO - TinderboxPrint: CPU usage
13.2% 09:34:53 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 48 09:34:53 INFO - TinderboxPrint: I/O write bytes / time
399,482,880 / 465,600 09:34:53 INFO - TinderboxPrint: CPU idle
8,379.0 (86.8%) 09:34:53 INFO - TinderboxPrint: CPU user
1,216.4 (12.6%) 09:34:53 INFO - TinderboxPrint: Swap in / out
0 / 0 09:34:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110501888; Read time: 0; Write time: 106332 09:34:53 INFO - run-tests - Wall time: 1194s; CPU: 13.0%; Read bytes: 421888; Write bytes: 288980992; Read time: 32; Write time: 359268 09:34:53 INFO - Running post-run listener: _upload_blobber_files 09:34:53 INFO - Blob upload gear active. 09:34:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:34:53 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 09:34:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:34:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:34:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:34:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:34:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:55 INFO - (blobuploader) - INFO - Done attempting. 09:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:55 INFO - (blobuploader) - INFO - Done attempting. 09:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:34:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:59 INFO - (blobuploader) - INFO - Done attempting. 09:34:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:34:59 INFO - Return code: 0 09:34:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:34:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:34:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1f4d66741d4888304d466ae1a3f4c7002ed81027dd547fd9f9b93c9379b158668510f33c66beba49c4efe768f99306bb5396735333e8a93ae53015d7207045f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be6e58bad55e1c36430b78d665a6b3617aeea067b71ef576fa40f64a3d5350c242bd9c809cfad869252224906d6c6d8edd6a2a728ec902122c4c4cf1c7f7d139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cef74f6424a372e09d866595ec7a12e8e82b291382d57c14b0a8b709aa2f953c60022c732c551a22d9d87f17e2fbb142a7478c7e0f6583ce2e56e7d6e8f46b80"} 09:34:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:34:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:34:59 INFO - Contents: 09:34:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1f4d66741d4888304d466ae1a3f4c7002ed81027dd547fd9f9b93c9379b158668510f33c66beba49c4efe768f99306bb5396735333e8a93ae53015d7207045f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be6e58bad55e1c36430b78d665a6b3617aeea067b71ef576fa40f64a3d5350c242bd9c809cfad869252224906d6c6d8edd6a2a728ec902122c4c4cf1c7f7d139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cef74f6424a372e09d866595ec7a12e8e82b291382d57c14b0a8b709aa2f953c60022c732c551a22d9d87f17e2fbb142a7478c7e0f6583ce2e56e7d6e8f46b80"} 09:34:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:34:59 INFO - Copying logs to upload dir... 09:34:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:34:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.721511 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-10 09:34:59.018018) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 09:34:59.022482) ========= 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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1f4d66741d4888304d466ae1a3f4c7002ed81027dd547fd9f9b93c9379b158668510f33c66beba49c4efe768f99306bb5396735333e8a93ae53015d7207045f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be6e58bad55e1c36430b78d665a6b3617aeea067b71ef576fa40f64a3d5350c242bd9c809cfad869252224906d6c6d8edd6a2a728ec902122c4c4cf1c7f7d139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cef74f6424a372e09d866595ec7a12e8e82b291382d57c14b0a8b709aa2f953c60022c732c551a22d9d87f17e2fbb142a7478c7e0f6583ce2e56e7d6e8f46b80"} build_url:https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/CN833Oi_ROyrv1PLTaM6zA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1f4d66741d4888304d466ae1a3f4c7002ed81027dd547fd9f9b93c9379b158668510f33c66beba49c4efe768f99306bb5396735333e8a93ae53015d7207045f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be6e58bad55e1c36430b78d665a6b3617aeea067b71ef576fa40f64a3d5350c242bd9c809cfad869252224906d6c6d8edd6a2a728ec902122c4c4cf1c7f7d139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cef74f6424a372e09d866595ec7a12e8e82b291382d57c14b0a8b709aa2f953c60022c732c551a22d9d87f17e2fbb142a7478c7e0f6583ce2e56e7d6e8f46b80"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 09:34:59.052481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:34:59.052807) ========= 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=5cf47e208febbcd81da23b2e0000026f-1481376017.651641-1574280670 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 09:34:59.140049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 09:34:59.140682) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 09:34:59.141309) ========= ========= Total master_lag: 0.19 =========