builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1471835322.58 results: success (0) buildid: 20160821184114 builduid: 4a0046a7648345c7b99cf4c4ec5bbeda revision: 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.584306) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.584748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.585045) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.596170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.596474) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-21 20:08:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.0M=0s 2016-08-21 20:08:42 (25.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077818 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.686100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.686386) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016780 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:42.735866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 20:08:42.736260) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec --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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-21 20:08:43,033 truncating revision to first 12 chars 2016-08-21 20:08:43,034 Setting DEBUG logging. 2016-08-21 20:08:43,034 attempt 1/10 2016-08-21 20:08:43,034 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1aedc9d071fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-21 20:08:43,897 unpacking tar archive at: autoland-1aedc9d071fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.182488 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-21 20:08:43.934037) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:43.934502) ========= script_repo_revision: 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:43.935153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:43.935595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-21 20:08:43.946662) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-21 20:08:43.946939) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:08:44 INFO - MultiFileLogger online at 20160821 20:08:44 in /builds/slave/test 20:08:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 20:08:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:44 INFO - {'append_to_log': False, 20:08:44 INFO - 'base_work_dir': '/builds/slave/test', 20:08:44 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 20:08:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:44 INFO - 'branch': 'Autoland-Non-PGO', 20:08:44 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:44 INFO - 'config_files': ('talos/linux_config.py',), 20:08:44 INFO - 'default_actions': ('clobber', 20:08:44 INFO - 'read-buildbot-config', 20:08:44 INFO - 'download-and-extract', 20:08:44 INFO - 'populate-webroot', 20:08:44 INFO - 'create-virtualenv', 20:08:44 INFO - 'install', 20:08:44 INFO - 'run-tests'), 20:08:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:44 INFO - 'download_minidump_stackwalk': True, 20:08:44 INFO - 'download_symbols': 'ondemand', 20:08:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:44 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:44 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:44 INFO - 'installer_path': 'installer.exe', 20:08:44 INFO - 'log_level': 'info', 20:08:44 INFO - 'log_name': 'talos', 20:08:44 INFO - 'log_to_console': True, 20:08:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:08:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:08:44 INFO - 'opt_config_files': (), 20:08:44 INFO - 'pip_index': False, 20:08:44 INFO - 'sps_profile': False, 20:08:44 INFO - 'sps_profile_interval': 0, 20:08:44 INFO - 'suite': 'dromaeojs', 20:08:44 INFO - 'system_bits': '32', 20:08:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:44 INFO - 'title': 'talos-linux64-ix-001', 20:08:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:44 INFO - 'use_talos_json': True, 20:08:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:44 INFO - 'work_dir': 'build'} 20:08:44 INFO - [mozharness: 2016-08-22 03:08:44.334591Z] Running clobber step. 20:08:44 INFO - Running pre-action listener: _resource_record_pre_action 20:08:44 INFO - Running main action method: clobber 20:08:44 INFO - rmtree: /builds/slave/test/build 20:08:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:45 INFO - Running post-action listener: _resource_record_post_action 20:08:45 INFO - [mozharness: 2016-08-22 03:08:45.302400Z] Finished clobber step (success) 20:08:45 INFO - [mozharness: 2016-08-22 03:08:45.302500Z] Running read-buildbot-config step. 20:08:45 INFO - Running pre-action listener: _resource_record_pre_action 20:08:45 INFO - Running main action method: read_buildbot_config 20:08:45 INFO - Using buildbot properties: 20:08:45 INFO - { 20:08:45 INFO - "project": "", 20:08:45 INFO - "product": "firefox", 20:08:45 INFO - "script_repo_revision": "production", 20:08:45 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 20:08:45 INFO - "repository": "", 20:08:45 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 20:08:45 INFO - "buildid": "20160821184114", 20:08:45 INFO - "pgo_build": "False", 20:08:45 INFO - "basedir": "/builds/slave/test", 20:08:45 INFO - "buildnumber": 440, 20:08:45 INFO - "slavename": "talos-linux64-ix-001", 20:08:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:08:45 INFO - "platform": "ubuntu64_hw", 20:08:45 INFO - "scheduler": "tests-autoland-linux64-talos", 20:08:45 INFO - "branch": "autoland", 20:08:45 INFO - "repo_path": "integration/autoland", 20:08:45 INFO - "revision": "1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec", 20:08:45 INFO - "stage_platform": "linux64", 20:08:45 INFO - "builduid": "4a0046a7648345c7b99cf4c4ec5bbeda", 20:08:45 INFO - "slavebuilddir": "test" 20:08:45 INFO - } 20:08:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:08:45 INFO - Running post-action listener: _resource_record_post_action 20:08:45 INFO - [mozharness: 2016-08-22 03:08:45.305226Z] Finished read-buildbot-config step (success) 20:08:45 INFO - [mozharness: 2016-08-22 03:08:45.305314Z] Running download-and-extract step. 20:08:45 INFO - Running pre-action listener: _resource_record_pre_action 20:08:45 INFO - Running main action method: download_and_extract 20:08:45 INFO - mkdir: /builds/slave/test/build/tests 20:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:45 INFO - trying https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:08:45 INFO - Downloaded 1361 bytes. 20:08:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:08:45 INFO - Using the following test package requirements: 20:08:45 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:08:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:08:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:08:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'jsshell-linux-x86_64.zip'], 20:08:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:08:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:08:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:08:45 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:08:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:08:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:08:45 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:08:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:45 INFO - trying https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:08:46 INFO - Downloaded 20330229 bytes. 20:08:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 20:08:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:50 INFO - trying https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:08:51 INFO - Downloaded 20330229 bytes. 20:08:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 20:08:56 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')]} 20:08:56 INFO - trying https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:08:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:08:58 INFO - Downloaded 14413981 bytes. 20:08:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 20:08: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')]} 20:08:58 INFO - trying https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:08:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:09:01 INFO - Downloaded 55772055 bytes. 20:09:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:01 INFO - mkdir: /builds/slave/test/properties 20:09:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:09:01 INFO - Writing to file /builds/slave/test/properties/build_url 20:09:01 INFO - Contents: 20:09:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:09:02 INFO - Running post-action listener: _resource_record_post_action 20:09:02 INFO - Running post-action listener: set_extra_try_arguments 20:09:02 INFO - [mozharness: 2016-08-22 03:09:02.107745Z] Finished download-and-extract step (success) 20:09:02 INFO - [mozharness: 2016-08-22 03:09:02.107979Z] Running populate-webroot step. 20:09:02 INFO - Running pre-action listener: _resource_record_pre_action 20:09:02 INFO - Running main action method: populate_webroot 20:09:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:09:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:09:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:09:02 INFO - '--tppagecycles', 20:09:02 INFO - '7'], 20:09:02 INFO - 'tests': ['tsvgm']}}, 20:09:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:09:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:09:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:09:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:09:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['damp', 'tps']}, 20:09:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'tests': ['damp', 'tps']}, 20:09:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['dromaeo_dom']}, 20:09:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:09:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['basic_compositor_video']}, 20:09:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:09:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'other-e10s': {'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['a11yr', 20:09:02 INFO - 'ts_paint', 20:09:02 INFO - 'tpaint', 20:09:02 INFO - 'sessionrestore', 20:09:02 INFO - 'sessionrestore_no_auto_restore', 20:09:02 INFO - 'tabpaint']}, 20:09:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['tsvgx', 20:09:02 INFO - 'tsvgr_opacity', 20:09:02 INFO - 'tart', 20:09:02 INFO - 'tscrollx', 20:09:02 INFO - 'cart']}, 20:09:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:09:02 INFO - 'tsvgr_opacity', 20:09:02 INFO - 'tart', 20:09:02 INFO - 'tscrollx', 20:09:02 INFO - 'cart']}, 20:09:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'talos_options': ['--disable-e10s'], 20:09:02 INFO - 'tests': ['tp5o']}, 20:09:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'tests': ['tp5o']}, 20:09:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'talos_options': ['--disable-e10s', 20:09:02 INFO - '--xperf_path', 20:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:02 INFO - 'tests': ['tp5n']}, 20:09:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:02 INFO - 'talos_options': ['--xperf_path', 20:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:02 INFO - 'tests': ['tp5n']}}, 20:09:02 INFO - 'talos.zip': {'path': '', 20:09:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:09:02 INFO - Running post-action listener: _resource_record_post_action 20:09:02 INFO - [mozharness: 2016-08-22 03:09:02.134347Z] Finished populate-webroot step (success) 20:09:02 INFO - [mozharness: 2016-08-22 03:09:02.134435Z] Running create-virtualenv step. 20:09:02 INFO - Running pre-action listener: _resource_record_pre_action 20:09:02 INFO - Running main action method: create_virtualenv 20:09:02 INFO - Creating virtualenv /builds/slave/test/build/venv 20:09:02 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 20:09:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:09:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:09:02 INFO - Using real prefix '/usr' 20:09:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:09:02 INFO - Installing distribute.............................................................................................................................................................................................done. 20:09:04 INFO - Installing pip.................done. 20:09:04 INFO - Return code: 0 20:09:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:09:04 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')]} 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:04 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')]} 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:04 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:04 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 20:09:04 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 20:09:04 INFO - Using env: {'DISPLAY': ':0', 20:09:04 INFO - 'HOME': '/home/cltbld', 20:09:04 INFO - 'LANG': 'en_US.UTF-8', 20:09:04 INFO - 'LANGUAGE': 'en_US:en', 20:09:04 INFO - 'LOGNAME': 'cltbld', 20:09:04 INFO - 'MAIL': '/var/mail/cltbld', 20:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:04 INFO - 'MOZ_NO_REMOTE': '1', 20:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:04 INFO - 'NO_EM_RESTART': '1', 20:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:04 INFO - 'PWD': '/builds/slave/test', 20:09:04 INFO - 'SHELL': '/bin/bash', 20:09:04 INFO - 'SHLVL': '1', 20:09:04 INFO - 'TERM': 'linux', 20:09:04 INFO - 'TMOUT': '86400', 20:09:04 INFO - 'USER': 'cltbld', 20:09:04 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:04 INFO - '_': '/tools/buildbot/bin/python'} 20:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:04 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 20:09:04 INFO - Cleaning up... 20:09:04 INFO - Return code: 0 20:09:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:09:04 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')]} 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:04 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')]} 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:04 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:04 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 20:09:04 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 20:09:04 INFO - Using env: {'DISPLAY': ':0', 20:09:04 INFO - 'HOME': '/home/cltbld', 20:09:04 INFO - 'LANG': 'en_US.UTF-8', 20:09:04 INFO - 'LANGUAGE': 'en_US:en', 20:09:04 INFO - 'LOGNAME': 'cltbld', 20:09:04 INFO - 'MAIL': '/var/mail/cltbld', 20:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:04 INFO - 'MOZ_NO_REMOTE': '1', 20:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:04 INFO - 'NO_EM_RESTART': '1', 20:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:04 INFO - 'PWD': '/builds/slave/test', 20:09:04 INFO - 'SHELL': '/bin/bash', 20:09:04 INFO - 'SHLVL': '1', 20:09:04 INFO - 'TERM': 'linux', 20:09:04 INFO - 'TMOUT': '86400', 20:09:04 INFO - 'USER': 'cltbld', 20:09:04 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:04 INFO - '_': '/tools/buildbot/bin/python'} 20:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:05 INFO - Downloading/unpacking psutil>=3.1.1 20:09:05 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 20:09:05 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 20:09:05 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 20:09:05 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 20:09:05 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 20:09:05 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 20:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:09:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:07 INFO - Installing collected packages: psutil 20:09:07 INFO - Running setup.py install for psutil 20:09:07 INFO - building 'psutil._psutil_linux' extension 20:09:07 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 20:09:07 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 20:09:07 INFO - building 'psutil._psutil_posix' extension 20:09:07 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 20:09:07 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 20:09:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:07 INFO - Successfully installed psutil 20:09:07 INFO - Cleaning up... 20:09:07 INFO - Return code: 0 20:09:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:09:07 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')]} 20:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 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')]} 20:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:07 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:07 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 20:09:07 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 20:09:07 INFO - Using env: {'DISPLAY': ':0', 20:09:07 INFO - 'HOME': '/home/cltbld', 20:09:07 INFO - 'LANG': 'en_US.UTF-8', 20:09:07 INFO - 'LANGUAGE': 'en_US:en', 20:09:07 INFO - 'LOGNAME': 'cltbld', 20:09:07 INFO - 'MAIL': '/var/mail/cltbld', 20:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:07 INFO - 'MOZ_NO_REMOTE': '1', 20:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:07 INFO - 'NO_EM_RESTART': '1', 20:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:07 INFO - 'PWD': '/builds/slave/test', 20:09:07 INFO - 'SHELL': '/bin/bash', 20:09:07 INFO - 'SHLVL': '1', 20:09:07 INFO - 'TERM': 'linux', 20:09:07 INFO - 'TMOUT': '86400', 20:09:07 INFO - 'USER': 'cltbld', 20:09:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:07 INFO - '_': '/tools/buildbot/bin/python'} 20:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:09:07 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 20:09:07 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 20:09:07 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 20:09:07 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 20:09:07 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 20:09:07 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 20:09:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:09:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:09:09 INFO - Installing collected packages: mozsystemmonitor 20:09:09 INFO - Running setup.py install for mozsystemmonitor 20:09:09 INFO - Successfully installed mozsystemmonitor 20:09:09 INFO - Cleaning up... 20:09:09 INFO - Return code: 0 20:09:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:09:09 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')]} 20:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:09 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')]} 20:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:09 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:09 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 20:09:09 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 20:09:09 INFO - Using env: {'DISPLAY': ':0', 20:09:09 INFO - 'HOME': '/home/cltbld', 20:09:09 INFO - 'LANG': 'en_US.UTF-8', 20:09:09 INFO - 'LANGUAGE': 'en_US:en', 20:09:09 INFO - 'LOGNAME': 'cltbld', 20:09:09 INFO - 'MAIL': '/var/mail/cltbld', 20:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:09 INFO - 'MOZ_NO_REMOTE': '1', 20:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:09 INFO - 'NO_EM_RESTART': '1', 20:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:09 INFO - 'PWD': '/builds/slave/test', 20:09:09 INFO - 'SHELL': '/bin/bash', 20:09:09 INFO - 'SHLVL': '1', 20:09:09 INFO - 'TERM': 'linux', 20:09:09 INFO - 'TMOUT': '86400', 20:09:09 INFO - 'USER': 'cltbld', 20:09:09 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:09 INFO - '_': '/tools/buildbot/bin/python'} 20:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:10 INFO - Downloading/unpacking jsonschema==2.5.1 20:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:09:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 INFO - Downloading functools32-3.2.3-2.tar.gz 20:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:09:12 INFO - warning: no files found matching '*.txt' 20:09:12 INFO - no previously-included directories found matching 'build' 20:09:12 INFO - no previously-included directories found matching 'dist' 20:09:12 INFO - no previously-included directories found matching '.git*' 20:09:12 INFO - Installing collected packages: jsonschema, functools32 20:09:12 INFO - Running setup.py install for functools32 20:09:12 INFO - warning: no files found matching '*.txt' 20:09:12 INFO - no previously-included directories found matching 'build' 20:09:12 INFO - no previously-included directories found matching 'dist' 20:09:12 INFO - no previously-included directories found matching '.git*' 20:09:12 INFO - Successfully installed jsonschema functools32 20:09:12 INFO - Cleaning up... 20:09:12 INFO - Return code: 0 20:09:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:09:12 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')]} 20:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 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')]} 20:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:12 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 20:09:12 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 20:09:12 INFO - Using env: {'DISPLAY': ':0', 20:09:12 INFO - 'HOME': '/home/cltbld', 20:09:12 INFO - 'LANG': 'en_US.UTF-8', 20:09:12 INFO - 'LANGUAGE': 'en_US:en', 20:09:12 INFO - 'LOGNAME': 'cltbld', 20:09:12 INFO - 'MAIL': '/var/mail/cltbld', 20:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:12 INFO - 'MOZ_NO_REMOTE': '1', 20:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:12 INFO - 'NO_EM_RESTART': '1', 20:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:12 INFO - 'PWD': '/builds/slave/test', 20:09:12 INFO - 'SHELL': '/bin/bash', 20:09:12 INFO - 'SHLVL': '1', 20:09:12 INFO - 'TERM': 'linux', 20:09:12 INFO - 'TMOUT': '86400', 20:09:12 INFO - 'USER': 'cltbld', 20:09:12 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:12 INFO - '_': '/tools/buildbot/bin/python'} 20:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:09:12 INFO - Cleaning up... 20:09:12 INFO - Return code: 0 20:09:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:09:12 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')]} 20:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 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')]} 20:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:12 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:12 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 20:09:12 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 20:09:12 INFO - Using env: {'DISPLAY': ':0', 20:09:12 INFO - 'HOME': '/home/cltbld', 20:09:12 INFO - 'LANG': 'en_US.UTF-8', 20:09:12 INFO - 'LANGUAGE': 'en_US:en', 20:09:12 INFO - 'LOGNAME': 'cltbld', 20:09:12 INFO - 'MAIL': '/var/mail/cltbld', 20:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:12 INFO - 'MOZ_NO_REMOTE': '1', 20:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:12 INFO - 'NO_EM_RESTART': '1', 20:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:12 INFO - 'PWD': '/builds/slave/test', 20:09:12 INFO - 'SHELL': '/bin/bash', 20:09:12 INFO - 'SHLVL': '1', 20:09:12 INFO - 'TERM': 'linux', 20:09:12 INFO - 'TMOUT': '86400', 20:09:12 INFO - 'USER': 'cltbld', 20:09:12 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:12 INFO - '_': '/tools/buildbot/bin/python'} 20:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:12 INFO - Downloading/unpacking blobuploader==1.2.4 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:12 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 20:09:14 INFO - Downloading blobuploader-1.2.4.tar.gz 20:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:09:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:09:14 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 20:09:14 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 20:09:14 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 20:09:14 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 20:09:14 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 20:09:14 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 20:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:09:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:09:15 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 20:09:15 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 20:09:15 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 20:09:15 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 20:09:15 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 20:09:15 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 20:09:15 INFO - Downloading docopt-0.6.1.tar.gz 20:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:09:15 INFO - Installing collected packages: blobuploader, requests, docopt 20:09:15 INFO - Running setup.py install for blobuploader 20:09:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:09:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:09:15 INFO - Running setup.py install for requests 20:09:16 INFO - Running setup.py install for docopt 20:09:16 INFO - Successfully installed blobuploader requests docopt 20:09:16 INFO - Cleaning up... 20:09:16 INFO - Return code: 0 20:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:16 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')]} 20:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:16 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')]} 20:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:16 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:16 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 20:09:16 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 20:09:16 INFO - Using env: {'DISPLAY': ':0', 20:09:16 INFO - 'HOME': '/home/cltbld', 20:09:16 INFO - 'LANG': 'en_US.UTF-8', 20:09:16 INFO - 'LANGUAGE': 'en_US:en', 20:09:16 INFO - 'LOGNAME': 'cltbld', 20:09:16 INFO - 'MAIL': '/var/mail/cltbld', 20:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:16 INFO - 'MOZ_NO_REMOTE': '1', 20:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:16 INFO - 'NO_EM_RESTART': '1', 20:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:16 INFO - 'PWD': '/builds/slave/test', 20:09:16 INFO - 'SHELL': '/bin/bash', 20:09:16 INFO - 'SHLVL': '1', 20:09:16 INFO - 'TERM': 'linux', 20:09:16 INFO - 'TMOUT': '86400', 20:09:16 INFO - 'USER': 'cltbld', 20:09:16 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:16 INFO - '_': '/tools/buildbot/bin/python'} 20:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:16 INFO - Running setup.py (path:/tmp/pip-ng7ZVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:16 INFO - Running setup.py (path:/tmp/pip-1do3n3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:16 INFO - Running setup.py (path:/tmp/pip-9k4G72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:16 INFO - Running setup.py (path:/tmp/pip-volw0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:16 INFO - Running setup.py (path:/tmp/pip-dQbwE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:16 INFO - Running setup.py (path:/tmp/pip-u6N683-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:16 INFO - Running setup.py (path:/tmp/pip-2VwXoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:16 INFO - Running setup.py (path:/tmp/pip-PxdlDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:16 INFO - Running setup.py (path:/tmp/pip-ifUa38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:17 INFO - Running setup.py (path:/tmp/pip-pSqw1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:17 INFO - Running setup.py (path:/tmp/pip-bB83uI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:17 INFO - Running setup.py (path:/tmp/pip-64phhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:17 INFO - Running setup.py (path:/tmp/pip-BbTw4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:17 INFO - Running setup.py (path:/tmp/pip-aHIUIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:17 INFO - Running setup.py (path:/tmp/pip-J4vWDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:17 INFO - Running setup.py (path:/tmp/pip-Qxw81e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:17 INFO - Running setup.py (path:/tmp/pip-XbWcjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:09:17 INFO - Running setup.py install for manifestparser 20:09:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:09:17 INFO - Running setup.py install for mozcrash 20:09:17 INFO - Running setup.py install for mozdebug 20:09:17 INFO - Running setup.py install for mozdevice 20:09:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:09:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozfile 20:09:18 INFO - Running setup.py install for mozhttpd 20:09:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozinfo 20:09:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozInstall 20:09:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozleak 20:09:18 INFO - Running setup.py install for mozlog 20:09:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for moznetwork 20:09:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozprocess 20:09:18 INFO - Running setup.py install for mozprofile 20:09:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:09:18 INFO - Running setup.py install for mozrunner 20:09:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:09:19 INFO - Running setup.py install for mozscreenshot 20:09:19 INFO - Running setup.py install for moztest 20:09:19 INFO - Running setup.py install for mozversion 20:09:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:09:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:09:19 INFO - Cleaning up... 20:09:19 INFO - Return code: 0 20:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:19 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')]} 20:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:19 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')]} 20:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:19 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:19 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 20:09:19 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 20:09:19 INFO - Using env: {'DISPLAY': ':0', 20:09:19 INFO - 'HOME': '/home/cltbld', 20:09:19 INFO - 'LANG': 'en_US.UTF-8', 20:09:19 INFO - 'LANGUAGE': 'en_US:en', 20:09:19 INFO - 'LOGNAME': 'cltbld', 20:09:19 INFO - 'MAIL': '/var/mail/cltbld', 20:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:19 INFO - 'MOZ_NO_REMOTE': '1', 20:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:19 INFO - 'NO_EM_RESTART': '1', 20:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:19 INFO - 'PWD': '/builds/slave/test', 20:09:19 INFO - 'SHELL': '/bin/bash', 20:09:19 INFO - 'SHLVL': '1', 20:09:19 INFO - 'TERM': 'linux', 20:09:19 INFO - 'TMOUT': '86400', 20:09:19 INFO - 'USER': 'cltbld', 20:09:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:19 INFO - '_': '/tools/buildbot/bin/python'} 20:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:19 INFO - Running setup.py (path:/tmp/pip-cnJTPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:19 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)) 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:19 INFO - Running setup.py (path:/tmp/pip-pXcsts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:19 INFO - Running setup.py (path:/tmp/pip-PHSHxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:19 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)) 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:19 INFO - Running setup.py (path:/tmp/pip-7McV0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:19 INFO - Running setup.py (path:/tmp/pip-A75X0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:19 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)) 20:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:19 INFO - Running setup.py (path:/tmp/pip-lJR0UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:20 INFO - Running setup.py (path:/tmp/pip-PUJnxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:20 INFO - Running setup.py (path:/tmp/pip-eMNnt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:20 INFO - Running setup.py (path:/tmp/pip-TJe7Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:20 INFO - Running setup.py (path:/tmp/pip-8EzCTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:20 INFO - Running setup.py (path:/tmp/pip-lUgm_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:20 INFO - Running setup.py (path:/tmp/pip-QdUpeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:20 INFO - Running setup.py (path:/tmp/pip-flyLTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:20 INFO - Running setup.py (path:/tmp/pip-l0Y20H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:20 INFO - Running setup.py (path:/tmp/pip-FEkbOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:20 INFO - Running setup.py (path:/tmp/pip-g1MV7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:20 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)) 20:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:20 INFO - Running setup.py (path:/tmp/pip-ZQnylX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:20 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)) 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:09:20 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)) 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:09:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:09:20 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 20:09:20 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 20:09:20 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 20:09:20 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 20:09:20 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 20:09:20 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 20:09:22 INFO - Downloading blessings-1.6.tar.gz 20:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:09:22 INFO - Installing collected packages: blessings 20:09:22 INFO - Running setup.py install for blessings 20:09:22 INFO - Successfully installed blessings 20:09:22 INFO - Cleaning up... 20:09:22 INFO - Return code: 0 20:09:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:09:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:23 INFO - Reading from file tmpfile_stdout 20:09:23 INFO - Current package versions: 20:09:23 INFO - argparse == 1.2.1 20:09:23 INFO - blessings == 1.6 20:09:23 INFO - blobuploader == 1.2.4 20:09:23 INFO - docopt == 0.6.1 20:09:23 INFO - functools32 == 3.2.3-2 20:09:23 INFO - jsonschema == 2.5.1 20:09:23 INFO - manifestparser == 1.1 20:09:23 INFO - mozInstall == 1.12 20:09:23 INFO - mozcrash == 0.17 20:09:23 INFO - mozdebug == 0.1 20:09:23 INFO - mozdevice == 0.48 20:09:23 INFO - mozfile == 1.2 20:09:23 INFO - mozhttpd == 0.7 20:09:23 INFO - mozinfo == 0.9 20:09:23 INFO - mozleak == 0.1 20:09:23 INFO - mozlog == 3.3 20:09:23 INFO - moznetwork == 0.27 20:09:23 INFO - mozprocess == 0.23 20:09:23 INFO - mozprofile == 0.28 20:09:23 INFO - mozrunner == 6.12 20:09:23 INFO - mozscreenshot == 0.1 20:09:23 INFO - mozsystemmonitor == 0.3 20:09:23 INFO - moztest == 0.7 20:09:23 INFO - mozversion == 1.4 20:09:23 INFO - psutil == 3.1.1 20:09:23 INFO - requests == 1.2.3 20:09:23 INFO - wsgiref == 0.1.2 20:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:23 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 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09:23 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 20:09:23 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 20:09:23 INFO - Using env: {'DISPLAY': ':0', 20:09:23 INFO - 'HOME': '/home/cltbld', 20:09:23 INFO - 'LANG': 'en_US.UTF-8', 20:09:23 INFO - 'LANGUAGE': 'en_US:en', 20:09:23 INFO - 'LOGNAME': 'cltbld', 20:09:23 INFO - 'MAIL': '/var/mail/cltbld', 20:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:23 INFO - 'MOZ_NO_REMOTE': '1', 20:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:23 INFO - 'NO_EM_RESTART': '1', 20:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:23 INFO - 'PWD': '/builds/slave/test', 20:09:23 INFO - 'SHELL': '/bin/bash', 20:09:23 INFO - 'SHLVL': '1', 20:09:23 INFO - 'TERM': 'linux', 20:09:23 INFO - 'TMOUT': '86400', 20:09:23 INFO - 'USER': 'cltbld', 20:09:23 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:23 INFO - '_': '/tools/buildbot/bin/python'} 20:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 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)) 20:09:23 INFO - Cleaning up... 20:09:23 INFO - Return code: 0 20:09:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6810daf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', '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 20:09: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', 'jsonschema'] in /builds/slave/test/build 20:09: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 jsonschema 20:09:23 INFO - Using env: {'DISPLAY': ':0', 20:09:23 INFO - 'HOME': '/home/cltbld', 20:09:23 INFO - 'LANG': 'en_US.UTF-8', 20:09:23 INFO - 'LANGUAGE': 'en_US:en', 20:09:23 INFO - 'LOGNAME': 'cltbld', 20:09:23 INFO - 'MAIL': '/var/mail/cltbld', 20:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:23 INFO - 'MOZ_NO_REMOTE': '1', 20:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:23 INFO - 'NO_EM_RESTART': '1', 20:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:23 INFO - 'PWD': '/builds/slave/test', 20:09:23 INFO - 'SHELL': '/bin/bash', 20:09:23 INFO - 'SHLVL': '1', 20:09:23 INFO - 'TERM': 'linux', 20:09:23 INFO - 'TMOUT': '86400', 20:09:23 INFO - 'USER': 'cltbld', 20:09:23 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:23 INFO - '_': '/tools/buildbot/bin/python'} 20:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:09:23 INFO - Cleaning up... 20:09:23 INFO - Return code: 0 20:09:23 INFO - Running post-action listener: _resource_record_post_action 20:09:23 INFO - Running post-action listener: _start_resource_monitoring 20:09:23 INFO - Starting resource monitoring. 20:09:23 INFO - [mozharness: 2016-08-22 03:09:23.649102Z] Finished create-virtualenv step (success) 20:09:23 INFO - [mozharness: 2016-08-22 03:09:23.649557Z] Running install step. 20:09:23 INFO - Running pre-action listener: _resource_record_pre_action 20:09:23 INFO - Running main action method: install 20:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:23 INFO - Reading from file tmpfile_stdout 20:09:23 INFO - Detecting whether we're running mozinstall >=1.0... 20:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:09:23 INFO - Reading from file tmpfile_stdout 20:09:23 INFO - Output received: 20:09:23 INFO - Usage: mozinstall [options] installer 20:09:23 INFO - Options: 20:09:23 INFO - -h, --help show this help message and exit 20:09:23 INFO - -d DEST, --destination=DEST 20:09:23 INFO - Directory to install application into. [default: 20:09:23 INFO - "/builds/slave/test"] 20:09:23 INFO - --app=APP Application being installed. [default: firefox] 20:09:23 INFO - mkdir: /builds/slave/test/build/application 20:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:09:40 INFO - Reading from file tmpfile_stdout 20:09:40 INFO - Output received: 20:09:40 INFO - /builds/slave/test/build/application/firefox/firefox 20:09:40 INFO - Running post-action listener: _resource_record_post_action 20:09:40 INFO - [mozharness: 2016-08-22 03:09:40.046381Z] Finished install step (success) 20:09:40 INFO - [mozharness: 2016-08-22 03:09:40.046720Z] Running run-tests step. 20:09:40 INFO - Running pre-action listener: _resource_record_pre_action 20:09:40 INFO - Running main action method: run_tests 20:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:40 INFO - Python 2.7.3 20:09:40 INFO - Return code: 0 20:09:40 INFO - grabbing minidump binary from tooltool 20:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:40 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 0xceb090>, '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 0xcee9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xceee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:40 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 20:09:40 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 20:09:40 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 20:09:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:40 INFO - Return code: 0 20:09:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:09:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:09:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 20:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 20:09:40 INFO - Using env: {'DISPLAY': ':0', 20:09:40 INFO - 'HOME': '/home/cltbld', 20:09:40 INFO - 'LANG': 'en_US.UTF-8', 20:09:40 INFO - 'LANGUAGE': 'en_US:en', 20:09:40 INFO - 'LOGNAME': 'cltbld', 20:09:40 INFO - 'MAIL': '/var/mail/cltbld', 20:09:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:40 INFO - 'MOZ_NO_REMOTE': '1', 20:09:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:40 INFO - 'NO_EM_RESTART': '1', 20:09:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:40 INFO - 'PWD': '/builds/slave/test', 20:09:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:40 INFO - 'SHELL': '/bin/bash', 20:09:40 INFO - 'SHLVL': '1', 20:09:40 INFO - 'TERM': 'linux', 20:09:40 INFO - 'TMOUT': '86400', 20:09:40 INFO - 'USER': 'cltbld', 20:09:40 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459', 20:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:40 INFO - '_': '/tools/buildbot/bin/python'} 20:09:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 20:09:40 INFO - mozversion application_buildid: 20160821184114 20:09:40 INFO - mozversion application_changeset: 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec 20:09:40 INFO - mozversion application_display_name: Nightly 20:09:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:40 INFO - mozversion application_name: Firefox 20:09:40 INFO - mozversion application_remotingname: firefox 20:09:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:09:40 INFO - mozversion application_vendor: Mozilla 20:09:40 INFO - mozversion application_version: 51.0a1 20:09:40 INFO - mozversion platform_buildid: 20160821184114 20:09:40 INFO - mozversion platform_changeset: 1aedc9d071fa9deb8ddf68aa025dc289daf8c5ec 20:09:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:09:40 INFO - mozversion platform_version: 51.0a1 20:09:40 INFO - using testdate: 1471835380 20:09:40 INFO - actual date: 1471835380 20:09:40 INFO - starting webserver on 'localhost:33945' 20:09:40 INFO - SUITE-START | Running 2 tests 20:09:40 INFO - TEST-START | dromaeo_css 20:09:40 INFO - operating with platform_type : linux_ 20:09:40 INFO - Initialising browser for dromaeo_css test... 20:09:40 INFO - TEST-INFO | started process 19017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZtrxgC/profile http://localhost:33945/getInfo.html) 20:09:44 INFO - PROCESS | 19017 | __metrics Screen width/height:1600/1200 20:09:44 INFO - PROCESS | 19017 | colorDepth:24 20:09:44 INFO - PROCESS | 19017 | Browser inner width/height: 1024/697 20:09:44 INFO - PROCESS | 19017 | __metrics 20:09:45 INFO - PROCESS | 19017 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:09:45 INFO - PROCESS | 19017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:46 INFO - PROCESS | 19017 | console.error: PushService: 20:09:46 INFO - PROCESS | 19017 | stateChangeProcessEnqueue: Error transitioning state 20:09:46 INFO - PROCESS | 19017 | UnknownError 20:09:46 INFO - TEST-INFO | 19017: exit 0 20:09:46 INFO - Browser initialized. 20:09:46 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:46 INFO - TEST-INFO | started process 19099 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZtrxgC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:59 INFO - PROCESS | 19099 | RSS: Main: 175833088 20:09:59 INFO - PROCESS | 19099 | 20:10:50 INFO - PROCESS | 19099 | 1471835450829 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] 20:13:03 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33945/tests/dromaeo/cssquery-ext.html) 20:13:04 INFO - PROCESS | 19099 | RSS: Main: 274046976 20:13:04 INFO - PROCESS | 19099 | 20:16:07 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-ext.html (next: http://localhost:33945/tests/dromaeo/cssquery-jquery.html) 20:16:07 INFO - PROCESS | 19099 | RSS: Main: 275243008 20:16:07 INFO - PROCESS | 19099 | 20:19:09 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33945/tests/dromaeo/cssquery-mootools.html) 20:19:10 INFO - PROCESS | 19099 | RSS: Main: 218443776 20:19:10 INFO - PROCESS | 19099 | 20:22:12 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33945/tests/dromaeo/cssquery-prototype.html) 20:22:13 INFO - PROCESS | 19099 | RSS: Main: 250150912 20:22:13 INFO - PROCESS | 19099 | 20:25:16 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33945/tests/dromaeo/cssquery-yui.html) 20:25:17 INFO - PROCESS | 19099 | RSS: Main: 219975680 20:25:17 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | Cycle 1(1): loaded http://localhost:33945/tests/dromaeo/cssquery-yui.html (next: http://localhost:33945/tests/dromaeo/cssquery-dojo.html) 20:28:20 INFO - PROCESS | 19099 | RSS: Main: 310362112 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | __start_tp_report 20:28:20 INFO - PROCESS | 19099 | _x_x_mozilla_page_load 20:28:20 INFO - PROCESS | 19099 | _x_x_mozilla_page_load_details 20:28:20 INFO - PROCESS | 19099 | |i|pagename|runs| 20:28:20 INFO - PROCESS | 19099 | |0;dojo.html;2497.4380764328025;2555.514452254072;2555.8594277314746;2561.142017424163;2568.062657130148;4736.881577960553;4767;4780;4888.999999999995;4926.236433352827;4767.11808315461;4803.975980120094;4860.8541743747655;4864.7081175129515;4891.4374846892615;4238.449001629784;4486.192485352635;4489.842855500058;4511.6165125964335;4537.727736335831;4497.370368148462;4522.68341216115;4548.7725613719185;4556.9088618227615;4557.0430209656015;4321.222180007598;4376.343548467724;4389.6488280937765;4449.488308844459;4493.7977790999485;4475.328700694889;4480.081583275453;4487.820487180509;4489.461264648263;4503.527129651372;4458.8885277867985;4463.129689710508;4471.7987690554;4472.776361181928;4488.573585509372;4685.2269375553;4752.120857641313;4771.069641419925;4782.0914026334885;4887.364642596475;5158.484151584811;5177.559907407875;5177.637565370425;5185.844424667266;5199.454057323965;5207.817726379559;5207.973960130213;5215.8174463893965;5236.188390799432;5237.711925844077;4623.190941585276;4762.595179409789;4784.784684689128;4813.15769740301;4872.098661747588;5336.91994620081;5375.3012108425655;5375.704336261465;5383.407825139231;5390.568754499631;5138.075146473596;5148.382194136728;5151.4333423323405;5153.510416510426;5156.355455568054;4974.67664601807;5099.056674515226;5144.099782538041;5164.25118357836;5240.633155679066;5158.484151584811;5169.043726910533;5170.586353091744;5175.249588809602;5194.7922083116255;5142.100132476801;5150.793968241228;5154.226865970135;5155.046316431397;5165.948340516622;4325.870223893288;4373.890652733707;4398.472183337956;4398.912021759547;4451.221036318631;3836.4820749199057;3870.8645197417954;3877.7091718121255;3879.5344558652578;3882.3400021996326;4458.4426946631675;4467.687261891701;4468.3074123510905;4475.127350166751;4476.955230447654;5035.521625445577;5131.7434128293435;5196.636235463481;5254.290670759475;5278.9208161877605;4373.081652852873;4446.133004064176;4450.332450132441;4456.175607512556;4465.531119232499;4751.358566593535;4760.190767569521;4763.071201115749;4764.6426518011285;4774.832880849153;4604.700694454849;4715.528447155394;4830.8309209178215;4840.394950631171;4846.636502262274;5144.382674079135;5223.033738758342;5246.449122842199;5246.606504512253;5248.212768084818;4801.9039619208115;4808.302796094476;4813.5186481352985;4814.927776083291;4815.30178124163;4853.441854186673;5020.799168033238;5140.742962851918;5145.151050076845;5153.201253805666;5111.207762796773;5194.636375453833;5209.947900521098;5210.296609957607;5254.054270231242;5085.211792172219;5094.949050509447;5103.821366252163;5104.591632669451;5107.489251074863;4901.950980490149;4968.503149685002;5078.08594453002;5105.030044291572;5112.642115051911;4916.508349165055;5008.273800299009;5014.598832093497;5017.8243761468175;5018.899622007612;5115.411727651368;5115.693058416507;5123.564497017646;5127;5131.076406246912;4796.56830885222;4800.879978000578;5001.224810154432;5016.122178618654;5021.171506701354 20:28:20 INFO - PROCESS | 19099 | |1;ext.html;534.5750128647725;553.5046133710326;557.637535601859;557.7155650618184;568.4230506036376;30390;37693.43459848101;39326.2134757305;39327.410088848694;42287.57712422875;39067.41398879019;39429.60570394295;39572.20855582887;39750.204995900065;39777.80111099448;7556.659950302222;7694.961525192366;7697.923020769805;7698.422618303622;7702.768916932473;7622.847543049135;7694.961525192366;7714.922850771477;7725.652345644431;7770.533767973911;7780.922190778077;7825.8826117608;7827.1390147083775;7841.568713720743;7849.411294152933;7710.2675245852115;7735.535867847919;7746.728864489716;7779.144294127642;7784.221577842255;7599.202083781179;7680.884786728204;7691.730789422343;7696.576688282143;7718.807029824299;37160.81419592885;37287;37413.81293093544;37442;37687.4346884797;2786.7770578353684;2843.4170994945985;2867.7992540522173;2885.1633026422314;3025.6066711327608;27677.446451070868;27678.169654910384;27858.721412785817;28858.567121493194;28929.42141157186;3418.453047512411;3487.843047062888;3497.807620580867;3561.786292822439;3634.8364323605238;239342;239863;240277;240555;240726;50263.48682565857;50586.241206381936;50647.49352506428;50666;50717.23924141141;52294.21558676623;52394.21408678873;52504.212436813476;52633.473665262856;52697;12517.624471265877;12864.22814631125;13293.468261269634;13343.065985381125;13556.11885227457;9925.1067403934;9995.650152244782;10111.797764044824;10120;10132;17417.912910435367;17486.737698934525;17515.64968700619;17564;17707;6734;6742.325767423316;6812.48906332027;6919.930800692029;6933.1333583302085;747.9962600186964;854.7606670132249;867.6529388244627;867.700643278068;924.6763632728625;25242.24273271805;26279.308957026133;26543.33641658974;27051.594226086625;27355.179344619693;848.240824462103;905.4793493741125;905.5879574793585;968.3705591365668;1016.7661437869332;13343.799843002364;13767.724645507034;14320.856791431952;14326.426942922375;14351;372057;374950;375618;376664;376902;47730.28404573934;48328.758356207996;48339.51660483421;48401.757991209815;48416.75791621019;1041.416806588313;1128.3230061962756;1134.5178299222798;1160.135698904288;1172.94135293235;26391.736082638927;27874.16377508678;27895.860520697268;28284.292892677848;28840;39715.602843972345;40209.79895100506;40265.194696105325;40267.597324026385;40269.39595906005;294846;296033;296483;296551;296733;19378.224871005;22948.4262893429;23265.651015234445;23678.526429471658;24059.278221653378;40218.39672404975;40807;40821.38767918424;41142;41527.792361038;35913.281734364646;36141.63858361488;36273.81863090667;36275.45586816147;36730.44904326384;36226.73206437762;36771.26457470889;37196.25607487889;37266.25467490689;38041.42937855879;35423.64576354204;35900;36372;36421.635783641825;36626.81686591657;30681.386372272875;33003.339933201685;33266.00201993944;33415.33169336648;33451.66548334583;24999.375015624755;25179.741012949646;25410.872945635154;25864.353391165372;26035.218943431726 20:28:20 INFO - PROCESS | 19099 | |2;jquery.html;342.95541579594646;360.9007522931195;365.4281050156505;367.5497515543459;384.6807150065446;12815.743685126292;16153.838461615394;16383.05105081085;16508.75236871448;17549.73675394867;15155.469558565452;15832.129232892186;16561.751573726375;16714;16722.66554668906;4966.528179822929;5177.249298851664;5221.138512145491;5452.236686863842;5512.586556008296;3492.0746002309406;5176.120059589879;5478.890422191554;5522.889542209154;5527.751251193706;5434.0218760623075;5480.424555421683;5501.119820828668;5504.366997795245;5504.972475137619;5487.451254874521;5492.752826122814;5646.520045796112;6014.248218972629;6074.51403887688;5364.6781193128145;5365.168398898177;5366.677999320014;5366.731663416814;5366.892662146735;12985.935070324682;20470.5905881883;20798.27206047781;20924.267650632308;21058.368248952556;1693.3395975569538;1916.865819392643;2111.4721319670084;2225.732912050564;2347.4835536182013;8493.957530212308;9162.26701863856;9351.906480935171;9593.088656577615;9621.759456013586;1658.2289235505548;1664.5755332390283;1684.065343734227;1685.1068933465283;1782.6256486137927;41803.883870392376;69735.30264697339;70621;72404.6379768103;72779.2722072783;9809.509524523744;9981.859342438649;10041.397516149054;10313.174946004452;10348.482575871176;13644.590662280147;13657.385417656229;13690;13694.726105477836;13698.109622874481;6645.767398141042;6844.794656160323;6953.721851125898;7025.89461158083;7161.212266650664;6443.162388889415;6515.185601799775;6662.700178491979;6679.998000299994;6697.162854643169;4266;4281.828726850953;4307.1601037797955;4347.652187824967;4352.608265256142;1776.6269083492352;1836.1370156026646;1848.2422206895108;1855.4804654696707;1868.6542989546977;805.1827395193883;816.5508970066439;827.9586020698941;829.4401279136558;874.5976850648773;16280.592985175228;17497.737533937;17572.45199446073;17621.64756704859;17934.19296131677;808.2321794295443;810.9067457242493;811.4482152136604;811.5577010529275;822.4489591973272;9065.138811812867;9566.952165239129;9622.711318660451;9780.168685661798;9830.901690982999;378002.74965629296;386151;387120;389447;391054;79793;80057;80924.59537702391;81054.594727026;82244.17755822485;889.7508697564692;889.902110767815;892.7768057985504;893.097971049232;899.2940541674753;15244.847551524786;15309.08145511273;15567.377304907677;15579.766303505683;16113.113778742281;37798;38106;38522;38560.80719596496;38810.417843731804;48198.518014819405;49001.05362501969;52337.7383113082;53154.73422633017;55550;4958.454569997225;6193.071039344134;6588.308227636221;7461.626918654154;7803.570803605856;10325;11284.661460156209;11421.543138274375;11556.42217889119;12616.243025418504;9521.809563808547;9656.855147172939;9696;9745.853812192681;10154.644587439405;9312.161905428544;9539.713808585753;9794.65718699842;9822.803543929223;9840.852387214341;9056.411333263317;9626.422414655142;9686.515674216402;9839.606415743576;10202.183825294107;6850.760223392157;6886.591593752661;6925.58446493212;7141.107361579802;7188.101487314086;9565.186959108554;9715.417074975525;9815;9912.058354456316;10268.58925642966 20:28:20 INFO - PROCESS | 19099 | |3;mootools.html;1251.1617216464967;1288.0854593238796;1355.6543081514212;1357.3688234970737;1640.60625449892;6681.4654827613795;7300.853982920345;7533.849323013544;7534.660940257688;7757.456978011542;7519.436042296822;7581.279778421042;7583.431242656809;7585.544867307952;7585.81035474112;2135.7864213578596;2161.3191844568987;2166.7508236552762;2170.1102547955343;2170.6309927312395;2147.1089497858447;2182.7162468879023;2210.8452408331423;2212.1814928476488;2215.1139544182374;2155.7844215578475;2174.0868835089223;2179.934601961944;2182.334388011656;2189.551142015891;2117.375374264596;2122.33146558835;2123.384218576611;2125.7767934366902;2127.2767259131815;2095.8742475451522;2141.539568992674;2142.2609199826193;2150.2044243629957;2151.3223334649533;7531.774046778551;7594.278543538355;7615.885761713579;7687.308142267167;7693.307602315763;4355.716878402925;4486.304622783474;4490.708103973231;4491.505934347219;4509.458864936196;2779.2912807234025;2896.174590241771;2957.4085182963277;2959.6470805709514;2972.7621790256726;967.6129548180713;969.0938972061145;978.7455261631956;985.1970643925179;990.7968866382374;10378.221633377452;10464.581416743322;10465.581376744922;10479.21405894561;10489.370637761758;4016.1365306458665;4121.423000779893;4159.043420013414;4279.914401711948;4332.220200363901;8262.256396924306;8358.247757701836;8376.916230837736;8378.162183781573;8380;4367.978160109243;4421.668374871898;4422.646188304928;4424.734515929054;4433.6009759121835;3520.894373168809;3797.3354662933875;3955.960440395617;4017.2367250222946;4020.276350256982;3297.0438572813614;3337.6161741399565;3414.6585341466152;3431.0564594736647;3458.5330980317335;1050.9474526273655;1054.478033373485;1061.3419679798574;1068.770214403907;1076.8438576406534;768.6041688530412;798.2376830127215;798.8002999250187;805.4482679364654;816.6610856494489;4857.125717370836;4883.8290659826735;4902.1176188285735;5048.444671086178;5229.032628963578;398.9800509974548;406.72342806891004;408.52610971273776;410.31477432687484;414.4922469974318;3157.921051973673;3226.306344135975;3311.850966706504;3369.3935091683734;3438.5186073949667;8518.829623407497;8873.245774109273;8948.731538053738;8960.417572857743;8998.325125615474;8944.597493112826;8950.86573701395;8955.552222388855;8972.955135224282;8974.775630609156;6758.222804377461;6912.412444942236;6958.025876377311;6962.895556566757;6963.825904352431;4772.451168115572;4780.1873681473535;4845.224764037737;4855.732934688484;4907.852764417074;8308.210719981593;8363.289120424832;8463.153684631488;8463.703770368007;8464.534450605275;8341.374396920133;8455.830883382176;8501.617427215913;8595;8601.870971935552;5624.409437009219;5875.911861322169;5999.220101386792;6135.969320153371;6142.14010038586;6719.260881302955;6953;6960.269171736894;6961.547499412522;6969.128858892638;6242.813865365566;6246.312905580292;6281.371862813682;6307.558470906993;6376.426121649074;6237.719302631302;6330.8100756977365;6338.397852204033;6350.015747559136;6389.616623002635;5758.1650660654805;5897.764089436375;5910.586258961831;5912;5912.408759124225;4236.5339812621205;4368.170047690928;4370.497392799742;4375.6718246132305;4409.250427427409;4474.79863406141;4499.572540608637;4515.638748900145;4600.539946005372;4730.290456431563 20:28:20 INFO - PROCESS | 19099 | |4;prototype.html;291.4171656686627;300.5596800686993;303.33720820008267;305.57525040194133;312.1384977462205;4648.604868586166;4816.472515835113;4838.274258861164;4871.5615594596475;4878.438979517352;4652.232381657022;4748.145333839908;4800.4719480857075;4807.423109226897;4820.252860806572;3847.9038024049482;3848.2495913296916;3863.130795570988;3865.5361356637104;3869.941950870739;3605.4231322988326;3625.7280703947176;3841.0973421245985;3850.400979338935;3858.8070596470207;3784.2431513697234;3803.581606023335;3808.771473711586;3812.866549670762;3844.2695887781374;3790.8483660653815;3798.7150963677695;3809.4857194278834;3813.417431765814;3822.120912190212;3352.8658853645825;3357.4628059510605;3360.54632624595;3373.342198271338;3384.5769278840144;4564.383808185886;4642.723251105939;4674.32222327764;4712.717236965759;4748.145333839908;4313.805878735465;4331.242032644274;4334.523202447728;4348.826046958118;4351.5648435156545;5031.345925029759;5041.747912604355;5049.065922804293;5064.417591976896;5077.162268225738;3586.7087848374563;3623.8731644392583;3639.3267245559655;3688.188598508324;3696.4085746280734;5211.42674305826;5220.216967454874;5221.921671174935;5234.842954711365;5242.344706911636;3747.4191500317493;3756.1173124315765;3756.6431189037;3767.171222331083;3768.3028639701743;3746.651205565994;3773.8301776419576;3807.809609519513;3812.542494900574;3818.217265460574;4151.4395556600075;4274.610751505773;4275.48694156697;4277.315629499265;4287.6355509782015;5010.3737032870895;5010.874728131826;5028.522290382408;5028.748562571856;5044.596432285409;1269.7270086931353;1284.9463439979313;1318.1761399125546;1324.7747882859937;1333.8666133386778;890.9955450222708;908.8636704494379;922.5663937949154;925.8240934222476;926.8100039491889;4335.263005289108;4354.216241076637;4367.410399596078;4368.453943257093;4371.78141092944;5108.463611320833;5127.307813445212;5133.589312854962;5143.665661731976;5149.45930677281;4359.564043595678;4365.345198220292;4379;4381.605655491021;4385.912281754347;2277.5786479501344;2450.644656524794;2458.287096741961;2459.88930498128;2465.7534246575556;4295.615933157065;4306.289462238696;4307.892302692458;4311.892202694895;4406.7576283304725;4670.789814458358;4689.366935463737;4700.247960326331;4732.810687572527;4736.8105275788585;4282.379055037002;4476.328550717419;4830.8792280193275;4857.417109946688;4868.659193856467;5251.291075704731;5253.684778913277;5256.9737151314;5260.44765299638;5270.73646317675;5145.8095980492335;5158.174692049254;5162.561182299547;5187.677142328682;5188.273641690091;4600.194965880891;4800.78347507164;4847.2729090636685;4858.805647774049;4861.173600487996;3650.5801832788507;3675.5038069860225;3676.724245681531;3705.4256590228556;3717.2379662169733;4991.775370108427;5289.629725919149;5297.17893726473;5303.7878484860175;5309.309789727311;4826.227803551415;5035.723035233097;5070.645054846125;5079.568236699774;5103.5661968733075;5037.924431133462;5044.848654540369;5055.345943379755;5057.14028615129;5063.924041139312;4795.256735206049;4957.677750946177;4987.850364489072;4995.600351971761;4998.3252260945765;5061.544460998528;5083.491650834887;5083.822066227813;5097.388313402415;5113.948860511496;4966.255061740768;4971.552560269593;4977.701337919736;4990.101781679333;5003.899922001467 20:28:20 INFO - PROCESS | 19099 | |5;yui.html;578.7511370110851;580.2196046317703;583.3728741602778;632.465566596226;681.57401623985;12424.503019879205;13220.53728119516;13313.80029299559;13335.199888006715;13347.733045339099;12564.24614523127;12642.304673242968;12800.167989080726;13116.868831311684;13408.396622152002;635.1774452084538;762.1273641680286;773.3001633521671;774.1368374262685;774.3650206830401;748.5920173505452;769.0655853138436;769.1885061260368;771.1478815908424;777.4907435629672;802.618756090858;802.9317508011826;803.3533005930224;803.3894240377297;803.6423791412824;655.849154694423;795.558465051896;797.7367468735304;797.7606717984611;799.5242830515863;577.9190913272146;588.355750453253;590.8463799412141;594.9940500594982;596.5943158652115;12431.62705118848;12455;12504.687382815411;12535.122541422104;12569.560065397667;12021.93989030058;12830.80753788694;12863.549775757803;12968.35158242084;13451.32743362828;11783.587574434854;11819.172657913949;11886.465109070112;12124.151309408346;12494.062945279049;4037.7577345359373;4088.693347998912;4121.91756164875;4336.674749393808;4340.370646256275;63707.36292637061;64046.35953640497;64108;64288.03567946485;64762.676186618766;9808.068233517804;9971.950140249251;9983.450910200008;10068.69793906184;10092.293539452168;19089.713654295196;19216.231350745813;19253.037348132537;19467.707984380246;19754.407367778702;6842.418394436529;6918.238993710688;6953.79138625842;6994.68523916425;7221.927780722231;3491.127218195451;3594.5506811648543;3637.1270894985014;3672.4491326300736;3776.4146557283666;2614.8823302951414;2714.3214196450886;2724.1146627346193;2727.1273192578183;2728.6316347292864;464.4612249790227;467.5114505341926;474;475.8072980442926;486.6009871905004;466.6546755401047;486.91235577595666;491.46675856695884;581.5144354463986;590.9025010873244;7736.226377362219;8228.341732661373;8271.8345633087;8338.541380224146;8400.621972011275;481.07393267959037;515.8504033830147;536.6109570561312;552.2875490617139;585.5666806563113;3828.6554210120667;3948.861789837342;4028.536718277377;4127.834886604563;4200.4959404871;100971;102482;103036;103729;106096.46951765192;87400;88099;88258;89400;89493.05069493128;49450.75274623676;54059;74654.25345746582;75979;78558;7392.445566582635;7585.241475852371;7755.302022817974;7932.801679958047;8066.596670166586;42027.36958945679;42630.14739705127;43091.35362969495;43205.6619899931;44979.77510112429;37384.71920221961;38172.80913595414;39840.800795995834;63390.366096340294;64152.358476416506;8816.911830881609;8989.730308090768;9117.908820911705;9284.58219380116;9341.626334946526;10111.905082917787;11193.328400296008;13209.471621135048;13343.266120363473;13364.866351336363;8469.74590762278;8855;9070.455772653662;9375.624975000923;10278.33190842593;11370.484552975011;11532.711682208012;11594.304341739522;11600.245984011011;11930.522779089086;11983.700407489883;12073.215241009306;12174.330411827434;13150.27673477968;13154.079214454896;9430;9830.164436023018;10104.84842727345;10156.187504999434;10385.480725963822;10235.283530152818;10269.229807764597;10410.739731506774;10504.369737815756;10619.30974486656 20:28:20 INFO - PROCESS | 19099 | __end_tp_report 20:28:20 INFO - PROCESS | 19099 | __start_cc_report 20:28:20 INFO - PROCESS | 19099 | _x_x_mozilla_cycle_collect,1090 20:28:20 INFO - PROCESS | 19099 | __end_cc_report 20:28:20 INFO - PROCESS | 19099 | __startTimestamp1471836500785__endTimestamp 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | ------- Summary: start ------- 20:28:20 INFO - PROCESS | 19099 | Number of tests: 6 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#0] dojo.html Cycles:165 Average:4782.58 Median:4864.71 stddev:523.44 (10.8%) stddev-sans-first:493.40 20:28:20 INFO - PROCESS | 19099 | Values: 2497.4 2555.5 2555.9 2561.1 2568.1 4736.9 4767.0 4780.0 4889.0 4926.2 4767.1 4804.0 4860.9 4864.7 4891.4 4238.4 4486.2 4489.8 4511.6 4537.7 4497.4 4522.7 4548.8 4556.9 4557.0 4321.2 4376.3 4389.6 4449.5 4493.8 4475.3 4480.1 4487.8 4489.5 4503.5 4458.9 4463.1 4471.8 4472.8 4488.6 4685.2 4752.1 4771.1 4782.1 4887.4 5158.5 5177.6 5177.6 5185.8 5199.5 5207.8 5208.0 5215.8 5236.2 5237.7 4623.2 4762.6 4784.8 4813.2 4872.1 5336.9 5375.3 5375.7 5383.4 5390.6 5138.1 5148.4 5151.4 5153.5 5156.4 4974.7 5099.1 5144.1 5164.3 5240.6 5158.5 5169.0 5170.6 5175.2 5194.8 5142.1 5150.8 5154.2 5155.0 5165.9 4325.9 4373.9 4398.5 4398.9 4451.2 3836.5 3870.9 3877.7 3879.5 3882.3 4458.4 4467.7 4468.3 4475.1 4477.0 5035.5 5131.7 5196.6 5254.3 5278.9 4373.1 4446.1 4450.3 4456.2 4465.5 4751.4 4760.2 4763.1 4764.6 4774.8 4604.7 4715.5 4830.8 4840.4 4846.6 5144.4 5223.0 5246.4 5246.6 5248.2 4801.9 4808.3 4813.5 4814.9 4815.3 4853.4 5020.8 5140.7 5145.2 5153.2 5111.2 5194.6 5209.9 5210.3 5254.1 5085.2 5094.9 5103.8 5104.6 5107.5 4902.0 4968.5 5078.1 5105.0 5112.6 4916.5 5008.3 5014.6 5017.8 5018.9 5115.4 5115.7 5123.6 5127.0 5131.1 4796.6 4800.9 5001.2 5016.1 5021.2 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#1] ext.html Cycles:180 Average:45601.84 Median:37276.63 stddev:81244.69 (218.0%) stddev-sans-first:81402.14 20:28:20 INFO - PROCESS | 19099 | Values: 534.6 553.5 557.6 557.7 568.4 30390.0 37693.4 39326.2 39327.4 42287.6 39067.4 39429.6 39572.2 39750.2 39777.8 7556.7 7695.0 7697.9 7698.4 7702.8 7622.8 7695.0 7714.9 7725.7 7770.5 7780.9 7825.9 7827.1 7841.6 7849.4 7710.3 7735.5 7746.7 7779.1 7784.2 7599.2 7680.9 7691.7 7696.6 7718.8 37160.8 37287.0 37413.8 37442.0 37687.4 2786.8 2843.4 2867.8 2885.2 3025.6 27677.4 27678.2 27858.7 28858.6 28929.4 3418.5 3487.8 3497.8 3561.8 3634.8 239342.0 239863.0 240277.0 240555.0 240726.0 50263.5 50586.2 50647.5 50666.0 50717.2 52294.2 52394.2 52504.2 52633.5 52697.0 12517.6 12864.2 13293.5 13343.1 13556.1 9925.1 9995.7 10111.8 10120.0 10132.0 17417.9 17486.7 17515.6 17564.0 17707.0 6734.0 6742.3 6812.5 6919.9 6933.1 748.0 854.8 867.7 867.7 924.7 25242.2 26279.3 26543.3 27051.6 27355.2 848.2 905.5 905.6 968.4 1016.8 13343.8 13767.7 14320.9 14326.4 14351.0 372057.0 374950.0 375618.0 376664.0 376902.0 47730.3 48328.8 48339.5 48401.8 48416.8 1041.4 1128.3 1134.5 1160.1 1172.9 26391.7 27874.2 27895.9 28284.3 28840.0 39715.6 40209.8 40265.2 40267.6 40269.4 294846.0 296033.0 296483.0 296551.0 296733.0 19378.2 22948.4 23265.7 23678.5 24059.3 40218.4 40807.0 40821.4 41142.0 41527.8 35913.3 36141.6 36273.8 36275.5 36730.4 36226.7 36771.3 37196.3 37266.3 38041.4 35423.6 35900.0 36372.0 36421.6 36626.8 30681.4 33003.3 33266.0 33415.3 33451.7 24999.4 25179.7 25410.9 25864.4 26035.2 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#2] jquery.html Cycles:180 Average:24075.95 Median:5366.81 stddev:63928.91 (1191.2%) stddev-sans-first:64083.41 20:28:20 INFO - PROCESS | 19099 | Values: 343.0 360.9 365.4 367.5 384.7 12815.7 16153.8 16383.1 16508.8 17549.7 15155.5 15832.1 16561.8 16714.0 16722.7 4966.5 5177.2 5221.1 5452.2 5512.6 3492.1 5176.1 5478.9 5522.9 5527.8 5434.0 5480.4 5501.1 5504.4 5505.0 5487.5 5492.8 5646.5 6014.2 6074.5 5364.7 5365.2 5366.7 5366.7 5366.9 12985.9 20470.6 20798.3 20924.3 21058.4 1693.3 1916.9 2111.5 2225.7 2347.5 8494.0 9162.3 9351.9 9593.1 9621.8 1658.2 1664.6 1684.1 1685.1 1782.6 41803.9 69735.3 70621.0 72404.6 72779.3 9809.5 9981.9 10041.4 10313.2 10348.5 13644.6 13657.4 13690.0 13694.7 13698.1 6645.8 6844.8 6953.7 7025.9 7161.2 6443.2 6515.2 6662.7 6680.0 6697.2 4266.0 4281.8 4307.2 4347.7 4352.6 1776.6 1836.1 1848.2 1855.5 1868.7 805.2 816.6 828.0 829.4 874.6 16280.6 17497.7 17572.5 17621.6 17934.2 808.2 810.9 811.4 811.6 822.4 9065.1 9567.0 9622.7 9780.2 9830.9 378002.7 386151.0 387120.0 389447.0 391054.0 79793.0 80057.0 80924.6 81054.6 82244.2 889.8 889.9 892.8 893.1 899.3 15244.8 15309.1 15567.4 15579.8 16113.1 37798.0 38106.0 38522.0 38560.8 38810.4 48198.5 49001.1 52337.7 53154.7 55550.0 4958.5 6193.1 6588.3 7461.6 7803.6 10325.0 11284.7 11421.5 11556.4 12616.2 9521.8 9656.9 9696.0 9745.9 10154.6 9312.2 9539.7 9794.7 9822.8 9840.9 9056.4 9626.4 9686.5 9839.6 10202.2 6850.8 6886.6 6925.6 7141.1 7188.1 9565.2 9715.4 9815.0 9912.1 10268.6 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#3] mootools.html Cycles:180 Average:4862.42 Median:4776.32 stddev:2713.81 (56.8%) stddev-sans-first:2707.85 20:28:20 INFO - PROCESS | 19099 | Values: 1251.2 1288.1 1355.7 1357.4 1640.6 6681.5 7300.9 7533.8 7534.7 7757.5 7519.4 7581.3 7583.4 7585.5 7585.8 2135.8 2161.3 2166.8 2170.1 2170.6 2147.1 2182.7 2210.8 2212.2 2215.1 2155.8 2174.1 2179.9 2182.3 2189.6 2117.4 2122.3 2123.4 2125.8 2127.3 2095.9 2141.5 2142.3 2150.2 2151.3 7531.8 7594.3 7615.9 7687.3 7693.3 4355.7 4486.3 4490.7 4491.5 4509.5 2779.3 2896.2 2957.4 2959.6 2972.8 967.6 969.1 978.7 985.2 990.8 10378.2 10464.6 10465.6 10479.2 10489.4 4016.1 4121.4 4159.0 4279.9 4332.2 8262.3 8358.2 8376.9 8378.2 8380.0 4368.0 4421.7 4422.6 4424.7 4433.6 3520.9 3797.3 3956.0 4017.2 4020.3 3297.0 3337.6 3414.7 3431.1 3458.5 1050.9 1054.5 1061.3 1068.8 1076.8 768.6 798.2 798.8 805.4 816.7 4857.1 4883.8 4902.1 5048.4 5229.0 399.0 406.7 408.5 410.3 414.5 3157.9 3226.3 3311.9 3369.4 3438.5 8518.8 8873.2 8948.7 8960.4 8998.3 8944.6 8950.9 8955.6 8973.0 8974.8 6758.2 6912.4 6958.0 6962.9 6963.8 4772.5 4780.2 4845.2 4855.7 4907.9 8308.2 8363.3 8463.2 8463.7 8464.5 8341.4 8455.8 8501.6 8595.0 8601.9 5624.4 5875.9 5999.2 6136.0 6142.1 6719.3 6953.0 6960.3 6961.5 6969.1 6242.8 6246.3 6281.4 6307.6 6376.4 6237.7 6330.8 6338.4 6350.0 6389.6 5758.2 5897.8 5910.6 5912.0 5912.4 4236.5 4368.2 4370.5 4375.7 4409.3 4474.8 4499.6 4515.6 4600.5 4730.3 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#4] prototype.html Cycles:180 Average:4157.71 Median:4650.42 stddev:1199.36 (25.8%) stddev-sans-first:1167.09 20:28:20 INFO - PROCESS | 19099 | Values: 291.4 300.6 303.3 305.6 312.1 4648.6 4816.5 4838.3 4871.6 4878.4 4652.2 4748.1 4800.5 4807.4 4820.3 3847.9 3848.2 3863.1 3865.5 3869.9 3605.4 3625.7 3841.1 3850.4 3858.8 3784.2 3803.6 3808.8 3812.9 3844.3 3790.8 3798.7 3809.5 3813.4 3822.1 3352.9 3357.5 3360.5 3373.3 3384.6 4564.4 4642.7 4674.3 4712.7 4748.1 4313.8 4331.2 4334.5 4348.8 4351.6 5031.3 5041.7 5049.1 5064.4 5077.2 3586.7 3623.9 3639.3 3688.2 3696.4 5211.4 5220.2 5221.9 5234.8 5242.3 3747.4 3756.1 3756.6 3767.2 3768.3 3746.7 3773.8 3807.8 3812.5 3818.2 4151.4 4274.6 4275.5 4277.3 4287.6 5010.4 5010.9 5028.5 5028.7 5044.6 1269.7 1284.9 1318.2 1324.8 1333.9 891.0 908.9 922.6 925.8 926.8 4335.3 4354.2 4367.4 4368.5 4371.8 5108.5 5127.3 5133.6 5143.7 5149.5 4359.6 4365.3 4379.0 4381.6 4385.9 2277.6 2450.6 2458.3 2459.9 2465.8 4295.6 4306.3 4307.9 4311.9 4406.8 4670.8 4689.4 4700.2 4732.8 4736.8 4282.4 4476.3 4830.9 4857.4 4868.7 5251.3 5253.7 5257.0 5260.4 5270.7 5145.8 5158.2 5162.6 5187.7 5188.3 4600.2 4800.8 4847.3 4858.8 4861.2 3650.6 3675.5 3676.7 3705.4 3717.2 4991.8 5289.6 5297.2 5303.8 5309.3 4826.2 5035.7 5070.6 5079.6 5103.6 5037.9 5044.8 5055.3 5057.1 5063.9 4795.3 4957.7 4987.9 4995.6 4998.3 5061.5 5083.5 5083.8 5097.4 5113.9 4966.3 4971.6 4977.7 4990.1 5003.9 20:28:20 INFO - PROCESS | 19099 | 20:28:20 INFO - PROCESS | 19099 | [#5] yui.html Cycles:180 Average:17371.46 Median:467.08 stddev:25375.03 (5432.7%) stddev-sans-first:25414.89 20:28:20 INFO - PROCESS | 19099 | Values: 578.8 580.2 583.4 632.5 681.6 12424.5 13220.5 13313.8 13335.2 13347.7 12564.2 12642.3 12800.2 13116.9 13408.4 635.2 762.1 773.3 774.1 774.4 748.6 769.1 769.2 771.1 777.5 802.6 802.9 803.4 803.4 803.6 655.8 795.6 797.7 797.8 799.5 577.9 588.4 590.8 595.0 596.6 12431.6 12455.0 12504.7 12535.1 12569.6 12021.9 12830.8 12863.5 12968.4 13451.3 11783.6 11819.2 11886.5 12124.2 12494.1 4037.8 4088.7 4121.9 4336.7 4340.4 63707.4 64046.4 64108.0 64288.0 64762.7 9808.1 9972.0 9983.5 10068.7 10092.3 19089.7 19216.2 19253.0 19467.7 19754.4 6842.4 6918.2 6953.8 6994.7 7221.9 3491.1 3594.6 3637.1 3672.4 3776.4 2614.9 2714.3 2724.1 2727.1 2728.6 464.5 467.5 474.0 475.8 486.6 466.7 486.9 491.5 581.5 590.9 7736.2 8228.3 8271.8 8338.5 8400.6 481.1 515.9 536.6 552.3 585.6 3828.7 3948.9 4028.5 4127.8 4200.5 100971.0 102482.0 103036.0 103729.0 106096.5 87400.0 88099.0 88258.0 89400.0 89493.1 49450.8 54059.0 74654.3 75979.0 78558.0 7392.4 7585.2 7755.3 7932.8 8066.6 42027.4 42630.1 43091.4 43205.7 44979.8 37384.7 38172.8 39840.8 63390.4 64152.4 8816.9 8989.7 9117.9 9284.6 9341.6 10111.9 11193.3 13209.5 13343.3 13364.9 8469.7 8855.0 9070.5 9375.6 10278.3 11370.5 11532.7 11594.3 11600.2 11930.5 11983.7 12073.2 12174.3 13150.3 13154.1 9430.0 9830.2 10104.8 10156.2 10385.5 10235.3 10269.2 10410.7 10504.4 10619.3 20:28:20 INFO - PROCESS | 19099 | -------- Summary: end -------- 20:28:20 INFO - PROCESS | 19099 | 20:28:21 INFO - TEST-INFO | 19099: exit 0 20:28:21 INFO - TEST-OK | dromaeo_css | took 1121417ms 20:28:21 INFO - TEST-START | kraken 20:28:21 INFO - operating with platform_type : linux_ 20:28:21 INFO - Initialising browser for kraken test... 20:28:21 INFO - TEST-INFO | started process 19402 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxeKSi/profile http://localhost:33945/getInfo.html) 20:28:26 INFO - PROCESS | 19402 | __metrics Screen width/height:1600/1200 20:28:26 INFO - PROCESS | 19402 | colorDepth:24 20:28:26 INFO - PROCESS | 19402 | Browser inner width/height: 1024/697 20:28:26 INFO - PROCESS | 19402 | __metrics 20:28:27 INFO - PROCESS | 19402 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:28:27 INFO - PROCESS | 19402 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:28:27 INFO - PROCESS | 19402 | console.error: PushService: 20:28:27 INFO - PROCESS | 19402 | stateChangeProcessEnqueue: Error transitioning state 20:28:27 INFO - PROCESS | 19402 | UnknownError 20:28:28 INFO - TEST-INFO | 19402: exit 0 20:28:28 INFO - Browser initialized. 20:28:28 INFO - Running cycle 1/1 for kraken test... 20:28:28 INFO - TEST-INFO | started process 19482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxeKSi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:40 INFO - PROCESS | 19482 | RSS: Main: 175550464 20:28:40 INFO - PROCESS | 19482 | 20:28:43 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33945/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:44 INFO - PROCESS | 19482 | RSS: Main: 236511232 20:28:44 INFO - PROCESS | 19482 | 20:28:46 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33945/tests/kraken/driver.html?testName=audio-dft) 20:28:47 INFO - PROCESS | 19482 | RSS: Main: 227168256 20:28:47 INFO - PROCESS | 19482 | 20:28:50 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33945/tests/kraken/driver.html?testName=audio-fft) 20:28:51 INFO - PROCESS | 19482 | RSS: Main: 302833664 20:28:51 INFO - PROCESS | 19482 | 20:28:53 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33945/tests/kraken/driver.html?testName=audio-oscillator) 20:28:54 INFO - PROCESS | 19482 | RSS: Main: 210382848 20:28:54 INFO - PROCESS | 19482 | 20:28:56 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:56 INFO - PROCESS | 19482 | RSS: Main: 214253568 20:28:56 INFO - PROCESS | 19482 | 20:29:00 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33945/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:00 INFO - PROCESS | 19482 | RSS: Main: 250490880 20:29:00 INFO - PROCESS | 19482 | 20:29:04 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33945/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:05 INFO - PROCESS | 19482 | RSS: Main: 244678656 20:29:05 INFO - PROCESS | 19482 | 20:29:08 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33945/tests/kraken/driver.html?testName=json-parse-financial) 20:29:08 INFO - PROCESS | 19482 | RSS: Main: 247193600 20:29:08 INFO - PROCESS | 19482 | 20:29:10 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:29:11 INFO - PROCESS | 19482 | RSS: Main: 211247104 20:29:11 INFO - PROCESS | 19482 | 20:29:13 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:29:13 INFO - PROCESS | 19482 | RSS: Main: 218955776 20:29:13 INFO - PROCESS | 19482 | 20:29:15 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:29:16 INFO - PROCESS | 19482 | RSS: Main: 224165888 20:29:16 INFO - PROCESS | 19482 | 20:29:19 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:29:20 INFO - PROCESS | 19482 | RSS: Main: 218177536 20:29:20 INFO - PROCESS | 19482 | 20:29:23 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:23 INFO - PROCESS | 19482 | RSS: Main: 221896704 20:29:23 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | Cycle 1(1): loaded http://localhost:33945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33945/tests/kraken/driver.html?testName=ai-astar) 20:29:25 INFO - PROCESS | 19482 | RSS: Main: 273727488 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | __start_tp_report 20:29:25 INFO - PROCESS | 19482 | _x_x_mozilla_page_load 20:29:25 INFO - PROCESS | 19482 | _x_x_mozilla_page_load_details 20:29:25 INFO - PROCESS | 19482 | |i|pagename|runs| 20:29:25 INFO - PROCESS | 19482 | |0;ai-astar;102;116;114;116;117;115;120;116;115;116 20:29:25 INFO - PROCESS | 19482 | |1;audio-beat-detection;149;128;151;185;128;151;148;129;186;121 20:29:25 INFO - PROCESS | 19482 | |2;audio-dft;161;162;163;160;162;162;161;161;162;161 20:29:25 INFO - PROCESS | 19482 | |3;audio-fft;75;75;76;168;75;75;76;121;108;79 20:29:25 INFO - PROCESS | 19482 | |4;audio-oscillator;85;86;146;86;86;86;141;85;85;136 20:29:25 INFO - PROCESS | 19482 | |5;imaging-gaussian-blur;118;117;117;118;117;118;120;118;117;117 20:29:25 INFO - PROCESS | 19482 | |6;imaging-darkroom;148;148;146;147;146;146;146;146;147;150 20:29:25 INFO - PROCESS | 19482 | |7;imaging-desaturate;95;95;95;95;95;94;94;95;95;95 20:29:25 INFO - PROCESS | 19482 | |8;json-parse-financial;64;65;66;67;67;66;66;69;66;67 20:29:25 INFO - PROCESS | 19482 | |9;json-stringify-tinderbox;66;65;65;66;66;65;65;68;66;65 20:29:25 INFO - PROCESS | 19482 | |10;stanford-crypto-aes;82;83;82;84;81;91;84;83;82;92 20:29:25 INFO - PROCESS | 19482 | |11;stanford-crypto-ccm;134;142;129;134;132;133;133;133;150;133 20:29:25 INFO - PROCESS | 19482 | |12;stanford-crypto-pbkdf2;171;182;177;173;176;177;170;174;175;177 20:29:25 INFO - PROCESS | 19482 | |13;stanford-crypto-sha256-iterative;72;68;69;70;68;68;67;68;67;67 20:29:25 INFO - PROCESS | 19482 | __end_tp_report 20:29:25 INFO - PROCESS | 19482 | __start_cc_report 20:29:25 INFO - PROCESS | 19482 | _x_x_mozilla_cycle_collect,2032 20:29:25 INFO - PROCESS | 19482 | __end_cc_report 20:29:25 INFO - PROCESS | 19482 | __startTimestamp1471836565722__endTimestamp 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | ------- Summary: start ------- 20:29:25 INFO - PROCESS | 19482 | Number of tests: 14 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:4.74 (4.1%) stddev-sans-first:1.69 20:29:25 INFO - PROCESS | 19482 | Values: 102.0 116.0 114.0 116.0 117.0 115.0 120.0 116.0 115.0 116.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:150.00 stddev:22.90 (15.3%) stddev-sans-first:24.29 20:29:25 INFO - PROCESS | 19482 | Values: 149.0 128.0 151.0 185.0 128.0 151.0 148.0 129.0 186.0 121.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 20:29:25 INFO - PROCESS | 19482 | Values: 161.0 162.0 163.0 160.0 162.0 162.0 161.0 161.0 162.0 161.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#3] audio-fft Cycles:10 Average:92.80 Median:75.00 stddev:31.10 (41.5%) stddev-sans-first:32.31 20:29:25 INFO - PROCESS | 19482 | Values: 75.0 75.0 76.0 168.0 75.0 75.0 76.0 121.0 108.0 79.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:85.50 stddev:26.88 (31.4%) stddev-sans-first:27.78 20:29:25 INFO - PROCESS | 19482 | Values: 85.0 86.0 146.0 86.0 86.0 86.0 141.0 85.0 85.0 136.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 20:29:25 INFO - PROCESS | 19482 | Values: 118.0 117.0 117.0 118.0 117.0 118.0 120.0 118.0 117.0 117.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:1.33 (0.9%) stddev-sans-first:1.36 20:29:25 INFO - PROCESS | 19482 | Values: 148.0 148.0 146.0 147.0 146.0 146.0 146.0 146.0 147.0 150.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:29:25 INFO - PROCESS | 19482 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:66.50 stddev:1.34 (2.0%) stddev-sans-first:1.13 20:29:25 INFO - PROCESS | 19482 | Values: 64.0 65.0 66.0 67.0 67.0 66.0 66.0 69.0 66.0 67.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 20:29:25 INFO - PROCESS | 19482 | Values: 66.0 65.0 65.0 66.0 66.0 65.0 65.0 68.0 66.0 65.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:3.86 (4.6%) stddev-sans-first:4.00 20:29:25 INFO - PROCESS | 19482 | Values: 82.0 83.0 82.0 84.0 81.0 91.0 84.0 83.0 82.0 92.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:133.50 stddev:6.11 (4.6%) stddev-sans-first:6.46 20:29:25 INFO - PROCESS | 19482 | Values: 134.0 142.0 129.0 134.0 132.0 133.0 133.0 133.0 150.0 133.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:176.50 stddev:3.46 (2.0%) stddev-sans-first:3.32 20:29:25 INFO - PROCESS | 19482 | Values: 171.0 182.0 177.0 173.0 176.0 177.0 170.0 174.0 175.0 177.0 20:29:25 INFO - PROCESS | 19482 | 20:29:25 INFO - PROCESS | 19482 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.58 (2.3%) stddev-sans-first:1.00 20:29:25 INFO - PROCESS | 19482 | Values: 72.0 68.0 69.0 70.0 68.0 68.0 67.0 68.0 67.0 67.0 20:29:25 INFO - PROCESS | 19482 | -------- Summary: end -------- 20:29:25 INFO - PROCESS | 19482 | 20:29:27 INFO - TEST-INFO | 19482: exit 0 20:29:27 INFO - TEST-OK | kraken | took 65340ms 20:29:27 INFO - SUITE-END | took 1186s 20:29:27 INFO - Completed test suite (00:19:47) 20:29:27 INFO - javascript benchmark 20:29:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2497.4380764328025, 2555.514452254072, 2555.8594277314746, 2561.142017424163, 2568.062657130148, 4736.881577960553, 4767.0, 4780.0, 4888.999999999995, 4926.236433352827, 4767.11808315461, 4803.975980120094, 4860.8541743747655, 4864.7081175129515, 4891.4374846892615, 4238.449001629784, 4486.192485352635, 4489.842855500058, 4511.6165125964335, 4537.727736335831, 4497.370368148462, 4522.68341216115, 4548.7725613719185, 4556.9088618227615, 4557.0430209656015, 4321.222180007598, 4376.343548467724, 4389.6488280937765, 4449.488308844459, 4493.7977790999485, 4475.328700694889, 4480.081583275453, 4487.820487180509, 4489.461264648263, 4503.527129651372, 4458.8885277867985, 4463.129689710508, 4471.7987690554, 4472.776361181928, 4488.573585509372, 4685.2269375553, 4752.120857641313, 4771.069641419925, 4782.0914026334885, 4887.364642596475, 5158.484151584811, 5177.559907407875, 5177.637565370425, 5185.844424667266, 5199.454057323965, 5207.817726379559, 5207.973960130213, 5215.8174463893965, 5236.188390799432, 5237.711925844077, 4623.190941585276, 4762.595179409789, 4784.784684689128, 4813.15769740301, 4872.098661747588, 5336.91994620081, 5375.3012108425655, 5375.704336261465, 5383.407825139231, 5390.568754499631, 5138.075146473596, 5148.382194136728, 5151.4333423323405, 5153.510416510426, 5156.355455568054, 4974.67664601807, 5099.056674515226, 5144.099782538041, 5164.25118357836, 5240.633155679066, 5158.484151584811, 5169.043726910533, 5170.586353091744, 5175.249588809602, 5194.7922083116255, 5142.100132476801, 5150.793968241228, 5154.226865970135, 5155.046316431397, 5165.948340516622, 4325.870223893288, 4373.890652733707, 4398.472183337956, 4398.912021759547, 4451.221036318631, 3836.4820749199057, 3870.8645197417954, 3877.7091718121255, 3879.5344558652578, 3882.3400021996326, 4458.4426946631675, 4467.687261891701, 4468.3074123510905, 4475.127350166751, 4476.955230447654, 5035.521625445577, 5131.7434128293435, 5196.636235463481, 5254.290670759475, 5278.9208161877605, 4373.081652852873, 4446.133004064176, 4450.332450132441, 4456.175607512556, 4465.531119232499, 4751.358566593535, 4760.190767569521, 4763.071201115749, 4764.6426518011285, 4774.832880849153, 4604.700694454849, 4715.528447155394, 4830.8309209178215, 4840.394950631171, 4846.636502262274, 5144.382674079135, 5223.033738758342, 5246.449122842199, 5246.606504512253, 5248.212768084818, 4801.9039619208115, 4808.302796094476, 4813.5186481352985, 4814.927776083291, 4815.30178124163, 4853.441854186673, 5020.799168033238, 5140.742962851918, 5145.151050076845, 5153.201253805666, 5111.207762796773, 5194.636375453833, 5209.947900521098, 5210.296609957607, 5254.054270231242, 5085.211792172219, 5094.949050509447, 5103.821366252163, 5104.591632669451, 5107.489251074863, 4901.950980490149, 4968.503149685002, 5078.08594453002, 5105.030044291572, 5112.642115051911, 4916.508349165055, 5008.273800299009, 5014.598832093497, 5017.8243761468175, 5018.899622007612, 5115.411727651368, 5115.693058416507, 5123.564497017646, 5127.0, 5131.076406246912, 4796.56830885222, 4800.879978000578, 5001.224810154432, 5016.122178618654, 5021.171506701354], "value": 4746.688479622154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.5750128647725, 553.5046133710326, 557.637535601859, 557.7155650618184, 568.4230506036376, 30390.0, 37693.43459848101, 39326.2134757305, 39327.410088848694, 42287.57712422875, 39067.41398879019, 39429.60570394295, 39572.20855582887, 39750.204995900065, 39777.80111099448, 7556.659950302222, 7694.961525192366, 7697.923020769805, 7698.422618303622, 7702.768916932473, 7622.847543049135, 7694.961525192366, 7714.922850771477, 7725.652345644431, 7770.533767973911, 7780.922190778077, 7825.8826117608, 7827.1390147083775, 7841.568713720743, 7849.411294152933, 7710.2675245852115, 7735.535867847919, 7746.728864489716, 7779.144294127642, 7784.221577842255, 7599.202083781179, 7680.884786728204, 7691.730789422343, 7696.576688282143, 7718.807029824299, 37160.81419592885, 37287.0, 37413.81293093544, 37442.0, 37687.4346884797, 2786.7770578353684, 2843.4170994945985, 2867.7992540522173, 2885.1633026422314, 3025.6066711327608, 27677.446451070868, 27678.169654910384, 27858.721412785817, 28858.567121493194, 28929.42141157186, 3418.453047512411, 3487.843047062888, 3497.807620580867, 3561.786292822439, 3634.8364323605238, 239342.0, 239863.0, 240277.0, 240555.0, 240726.0, 50263.48682565857, 50586.241206381936, 50647.49352506428, 50666.0, 50717.23924141141, 52294.21558676623, 52394.21408678873, 52504.212436813476, 52633.473665262856, 52697.0, 12517.624471265877, 12864.22814631125, 13293.468261269634, 13343.065985381125, 13556.11885227457, 9925.1067403934, 9995.650152244782, 10111.797764044824, 10120.0, 10132.0, 17417.912910435367, 17486.737698934525, 17515.64968700619, 17564.0, 17707.0, 6734.0, 6742.325767423316, 6812.48906332027, 6919.930800692029, 6933.1333583302085, 747.9962600186964, 854.7606670132249, 867.6529388244627, 867.700643278068, 924.6763632728625, 25242.24273271805, 26279.308957026133, 26543.33641658974, 27051.594226086625, 27355.179344619693, 848.240824462103, 905.4793493741125, 905.5879574793585, 968.3705591365668, 1016.7661437869332, 13343.799843002364, 13767.724645507034, 14320.856791431952, 14326.426942922375, 14351.0, 372057.0, 374950.0, 375618.0, 376664.0, 376902.0, 47730.28404573934, 48328.758356207996, 48339.51660483421, 48401.757991209815, 48416.75791621019, 1041.416806588313, 1128.3230061962756, 1134.5178299222798, 1160.135698904288, 1172.94135293235, 26391.736082638927, 27874.16377508678, 27895.860520697268, 28284.292892677848, 28840.0, 39715.602843972345, 40209.79895100506, 40265.194696105325, 40267.597324026385, 40269.39595906005, 294846.0, 296033.0, 296483.0, 296551.0, 296733.0, 19378.224871005, 22948.4262893429, 23265.651015234445, 23678.526429471658, 24059.278221653378, 40218.39672404975, 40807.0, 40821.38767918424, 41142.0, 41527.792361038, 35913.281734364646, 36141.63858361488, 36273.81863090667, 36275.45586816147, 36730.44904326384, 36226.73206437762, 36771.26457470889, 37196.25607487889, 37266.25467490689, 38041.42937855879, 35423.64576354204, 35900.0, 36372.0, 36421.635783641825, 36626.81686591657, 30681.386372272875, 33003.339933201685, 33266.00201993944, 33415.33169336648, 33451.66548334583, 24999.375015624755, 25179.741012949646, 25410.872945635154, 25864.353391165372, 26035.218943431726], "value": 16968.42443838739, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.95541579594646, 360.9007522931195, 365.4281050156505, 367.5497515543459, 384.6807150065446, 12815.743685126292, 16153.838461615394, 16383.05105081085, 16508.75236871448, 17549.73675394867, 15155.469558565452, 15832.129232892186, 16561.751573726375, 16714.0, 16722.66554668906, 4966.528179822929, 5177.249298851664, 5221.138512145491, 5452.236686863842, 5512.586556008296, 3492.0746002309406, 5176.120059589879, 5478.890422191554, 5522.889542209154, 5527.751251193706, 5434.0218760623075, 5480.424555421683, 5501.119820828668, 5504.366997795245, 5504.972475137619, 5487.451254874521, 5492.752826122814, 5646.520045796112, 6014.248218972629, 6074.51403887688, 5364.6781193128145, 5365.168398898177, 5366.677999320014, 5366.731663416814, 5366.892662146735, 12985.935070324682, 20470.5905881883, 20798.27206047781, 20924.267650632308, 21058.368248952556, 1693.3395975569538, 1916.865819392643, 2111.4721319670084, 2225.732912050564, 2347.4835536182013, 8493.957530212308, 9162.26701863856, 9351.906480935171, 9593.088656577615, 9621.759456013586, 1658.2289235505548, 1664.5755332390283, 1684.065343734227, 1685.1068933465283, 1782.6256486137927, 41803.883870392376, 69735.30264697339, 70621.0, 72404.6379768103, 72779.2722072783, 9809.509524523744, 9981.859342438649, 10041.397516149054, 10313.174946004452, 10348.482575871176, 13644.590662280147, 13657.385417656229, 13690.0, 13694.726105477836, 13698.109622874481, 6645.767398141042, 6844.794656160323, 6953.721851125898, 7025.89461158083, 7161.212266650664, 6443.162388889415, 6515.185601799775, 6662.700178491979, 6679.998000299994, 6697.162854643169, 4266.0, 4281.828726850953, 4307.1601037797955, 4347.652187824967, 4352.608265256142, 1776.6269083492352, 1836.1370156026646, 1848.2422206895108, 1855.4804654696707, 1868.6542989546977, 805.1827395193883, 816.5508970066439, 827.9586020698941, 829.4401279136558, 874.5976850648773, 16280.592985175228, 17497.737533937, 17572.45199446073, 17621.64756704859, 17934.19296131677, 808.2321794295443, 810.9067457242493, 811.4482152136604, 811.5577010529275, 822.4489591973272, 9065.138811812867, 9566.952165239129, 9622.711318660451, 9780.168685661798, 9830.901690982999, 378002.74965629296, 386151.0, 387120.0, 389447.0, 391054.0, 79793.0, 80057.0, 80924.59537702391, 81054.594727026, 82244.17755822485, 889.7508697564692, 889.902110767815, 892.7768057985504, 893.097971049232, 899.2940541674753, 15244.847551524786, 15309.08145511273, 15567.377304907677, 15579.766303505683, 16113.113778742281, 37798.0, 38106.0, 38522.0, 38560.80719596496, 38810.417843731804, 48198.518014819405, 49001.05362501969, 52337.7383113082, 53154.73422633017, 55550.0, 4958.454569997225, 6193.071039344134, 6588.308227636221, 7461.626918654154, 7803.570803605856, 10325.0, 11284.661460156209, 11421.543138274375, 11556.42217889119, 12616.243025418504, 9521.809563808547, 9656.855147172939, 9696.0, 9745.853812192681, 10154.644587439405, 9312.161905428544, 9539.713808585753, 9794.65718699842, 9822.803543929223, 9840.852387214341, 9056.411333263317, 9626.422414655142, 9686.515674216402, 9839.606415743576, 10202.183825294107, 6850.760223392157, 6886.591593752661, 6925.58446493212, 7141.107361579802, 7188.101487314086, 9565.186959108554, 9715.417074975525, 9815.0, 9912.058354456316, 10268.58925642966], "value": 8043.7590760270705, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.1617216464967, 1288.0854593238796, 1355.6543081514212, 1357.3688234970737, 1640.60625449892, 6681.4654827613795, 7300.853982920345, 7533.849323013544, 7534.660940257688, 7757.456978011542, 7519.436042296822, 7581.279778421042, 7583.431242656809, 7585.544867307952, 7585.81035474112, 2135.7864213578596, 2161.3191844568987, 2166.7508236552762, 2170.1102547955343, 2170.6309927312395, 2147.1089497858447, 2182.7162468879023, 2210.8452408331423, 2212.1814928476488, 2215.1139544182374, 2155.7844215578475, 2174.0868835089223, 2179.934601961944, 2182.334388011656, 2189.551142015891, 2117.375374264596, 2122.33146558835, 2123.384218576611, 2125.7767934366902, 2127.2767259131815, 2095.8742475451522, 2141.539568992674, 2142.2609199826193, 2150.2044243629957, 2151.3223334649533, 7531.774046778551, 7594.278543538355, 7615.885761713579, 7687.308142267167, 7693.307602315763, 4355.716878402925, 4486.304622783474, 4490.708103973231, 4491.505934347219, 4509.458864936196, 2779.2912807234025, 2896.174590241771, 2957.4085182963277, 2959.6470805709514, 2972.7621790256726, 967.6129548180713, 969.0938972061145, 978.7455261631956, 985.1970643925179, 990.7968866382374, 10378.221633377452, 10464.581416743322, 10465.581376744922, 10479.21405894561, 10489.370637761758, 4016.1365306458665, 4121.423000779893, 4159.043420013414, 4279.914401711948, 4332.220200363901, 8262.256396924306, 8358.247757701836, 8376.916230837736, 8378.162183781573, 8380.0, 4367.978160109243, 4421.668374871898, 4422.646188304928, 4424.734515929054, 4433.6009759121835, 3520.894373168809, 3797.3354662933875, 3955.960440395617, 4017.2367250222946, 4020.276350256982, 3297.0438572813614, 3337.6161741399565, 3414.6585341466152, 3431.0564594736647, 3458.5330980317335, 1050.9474526273655, 1054.478033373485, 1061.3419679798574, 1068.770214403907, 1076.8438576406534, 768.6041688530412, 798.2376830127215, 798.8002999250187, 805.4482679364654, 816.6610856494489, 4857.125717370836, 4883.8290659826735, 4902.1176188285735, 5048.444671086178, 5229.032628963578, 398.9800509974548, 406.72342806891004, 408.52610971273776, 410.31477432687484, 414.4922469974318, 3157.921051973673, 3226.306344135975, 3311.850966706504, 3369.3935091683734, 3438.5186073949667, 8518.829623407497, 8873.245774109273, 8948.731538053738, 8960.417572857743, 8998.325125615474, 8944.597493112826, 8950.86573701395, 8955.552222388855, 8972.955135224282, 8974.775630609156, 6758.222804377461, 6912.412444942236, 6958.025876377311, 6962.895556566757, 6963.825904352431, 4772.451168115572, 4780.1873681473535, 4845.224764037737, 4855.732934688484, 4907.852764417074, 8308.210719981593, 8363.289120424832, 8463.153684631488, 8463.703770368007, 8464.534450605275, 8341.374396920133, 8455.830883382176, 8501.617427215913, 8595.0, 8601.870971935552, 5624.409437009219, 5875.911861322169, 5999.220101386792, 6135.969320153371, 6142.14010038586, 6719.260881302955, 6953.0, 6960.269171736894, 6961.547499412522, 6969.128858892638, 6242.813865365566, 6246.312905580292, 6281.371862813682, 6307.558470906993, 6376.426121649074, 6237.719302631302, 6330.8100756977365, 6338.397852204033, 6350.015747559136, 6389.616623002635, 5758.1650660654805, 5897.764089436375, 5910.586258961831, 5912.0, 5912.408759124225, 4236.5339812621205, 4368.170047690928, 4370.497392799742, 4375.6718246132305, 4409.250427427409, 4474.79863406141, 4499.572540608637, 4515.638748900145, 4600.539946005372, 4730.290456431563], "value": 3876.298538473569, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.4171656686627, 300.5596800686993, 303.33720820008267, 305.57525040194133, 312.1384977462205, 4648.604868586166, 4816.472515835113, 4838.274258861164, 4871.5615594596475, 4878.438979517352, 4652.232381657022, 4748.145333839908, 4800.4719480857075, 4807.423109226897, 4820.252860806572, 3847.9038024049482, 3848.2495913296916, 3863.130795570988, 3865.5361356637104, 3869.941950870739, 3605.4231322988326, 3625.7280703947176, 3841.0973421245985, 3850.400979338935, 3858.8070596470207, 3784.2431513697234, 3803.581606023335, 3808.771473711586, 3812.866549670762, 3844.2695887781374, 3790.8483660653815, 3798.7150963677695, 3809.4857194278834, 3813.417431765814, 3822.120912190212, 3352.8658853645825, 3357.4628059510605, 3360.54632624595, 3373.342198271338, 3384.5769278840144, 4564.383808185886, 4642.723251105939, 4674.32222327764, 4712.717236965759, 4748.145333839908, 4313.805878735465, 4331.242032644274, 4334.523202447728, 4348.826046958118, 4351.5648435156545, 5031.345925029759, 5041.747912604355, 5049.065922804293, 5064.417591976896, 5077.162268225738, 3586.7087848374563, 3623.8731644392583, 3639.3267245559655, 3688.188598508324, 3696.4085746280734, 5211.42674305826, 5220.216967454874, 5221.921671174935, 5234.842954711365, 5242.344706911636, 3747.4191500317493, 3756.1173124315765, 3756.6431189037, 3767.171222331083, 3768.3028639701743, 3746.651205565994, 3773.8301776419576, 3807.809609519513, 3812.542494900574, 3818.217265460574, 4151.4395556600075, 4274.610751505773, 4275.48694156697, 4277.315629499265, 4287.6355509782015, 5010.3737032870895, 5010.874728131826, 5028.522290382408, 5028.748562571856, 5044.596432285409, 1269.7270086931353, 1284.9463439979313, 1318.1761399125546, 1324.7747882859937, 1333.8666133386778, 890.9955450222708, 908.8636704494379, 922.5663937949154, 925.8240934222476, 926.8100039491889, 4335.263005289108, 4354.216241076637, 4367.410399596078, 4368.453943257093, 4371.78141092944, 5108.463611320833, 5127.307813445212, 5133.589312854962, 5143.665661731976, 5149.45930677281, 4359.564043595678, 4365.345198220292, 4379.0, 4381.605655491021, 4385.912281754347, 2277.5786479501344, 2450.644656524794, 2458.287096741961, 2459.88930498128, 2465.7534246575556, 4295.615933157065, 4306.289462238696, 4307.892302692458, 4311.892202694895, 4406.7576283304725, 4670.789814458358, 4689.366935463737, 4700.247960326331, 4732.810687572527, 4736.8105275788585, 4282.379055037002, 4476.328550717419, 4830.8792280193275, 4857.417109946688, 4868.659193856467, 5251.291075704731, 5253.684778913277, 5256.9737151314, 5260.44765299638, 5270.73646317675, 5145.8095980492335, 5158.174692049254, 5162.561182299547, 5187.677142328682, 5188.273641690091, 4600.194965880891, 4800.78347507164, 4847.2729090636685, 4858.805647774049, 4861.173600487996, 3650.5801832788507, 3675.5038069860225, 3676.724245681531, 3705.4256590228556, 3717.2379662169733, 4991.775370108427, 5289.629725919149, 5297.17893726473, 5303.7878484860175, 5309.309789727311, 4826.227803551415, 5035.723035233097, 5070.645054846125, 5079.568236699774, 5103.5661968733075, 5037.924431133462, 5044.848654540369, 5055.345943379755, 5057.14028615129, 5063.924041139312, 4795.256735206049, 4957.677750946177, 4987.850364489072, 4995.600351971761, 4998.3252260945765, 5061.544460998528, 5083.491650834887, 5083.822066227813, 5097.388313402415, 5113.948860511496, 4966.255061740768, 4971.552560269593, 4977.701337919736, 4990.101781679333, 5003.899922001467], "value": 3782.2961276584247, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.7511370110851, 580.2196046317703, 583.3728741602778, 632.465566596226, 681.57401623985, 12424.503019879205, 13220.53728119516, 13313.80029299559, 13335.199888006715, 13347.733045339099, 12564.24614523127, 12642.304673242968, 12800.167989080726, 13116.868831311684, 13408.396622152002, 635.1774452084538, 762.1273641680286, 773.3001633521671, 774.1368374262685, 774.3650206830401, 748.5920173505452, 769.0655853138436, 769.1885061260368, 771.1478815908424, 777.4907435629672, 802.618756090858, 802.9317508011826, 803.3533005930224, 803.3894240377297, 803.6423791412824, 655.849154694423, 795.558465051896, 797.7367468735304, 797.7606717984611, 799.5242830515863, 577.9190913272146, 588.355750453253, 590.8463799412141, 594.9940500594982, 596.5943158652115, 12431.62705118848, 12455.0, 12504.687382815411, 12535.122541422104, 12569.560065397667, 12021.93989030058, 12830.80753788694, 12863.549775757803, 12968.35158242084, 13451.32743362828, 11783.587574434854, 11819.172657913949, 11886.465109070112, 12124.151309408346, 12494.062945279049, 4037.7577345359373, 4088.693347998912, 4121.91756164875, 4336.674749393808, 4340.370646256275, 63707.36292637061, 64046.35953640497, 64108.0, 64288.03567946485, 64762.676186618766, 9808.068233517804, 9971.950140249251, 9983.450910200008, 10068.69793906184, 10092.293539452168, 19089.713654295196, 19216.231350745813, 19253.037348132537, 19467.707984380246, 19754.407367778702, 6842.418394436529, 6918.238993710688, 6953.79138625842, 6994.68523916425, 7221.927780722231, 3491.127218195451, 3594.5506811648543, 3637.1270894985014, 3672.4491326300736, 3776.4146557283666, 2614.8823302951414, 2714.3214196450886, 2724.1146627346193, 2727.1273192578183, 2728.6316347292864, 464.4612249790227, 467.5114505341926, 474.0, 475.8072980442926, 486.6009871905004, 466.6546755401047, 486.91235577595666, 491.46675856695884, 581.5144354463986, 590.9025010873244, 7736.226377362219, 8228.341732661373, 8271.8345633087, 8338.541380224146, 8400.621972011275, 481.07393267959037, 515.8504033830147, 536.6109570561312, 552.2875490617139, 585.5666806563113, 3828.6554210120667, 3948.861789837342, 4028.536718277377, 4127.834886604563, 4200.4959404871, 100971.0, 102482.0, 103036.0, 103729.0, 106096.46951765192, 87400.0, 88099.0, 88258.0, 89400.0, 89493.05069493128, 49450.75274623676, 54059.0, 74654.25345746582, 75979.0, 78558.0, 7392.445566582635, 7585.241475852371, 7755.302022817974, 7932.801679958047, 8066.596670166586, 42027.36958945679, 42630.14739705127, 43091.35362969495, 43205.6619899931, 44979.77510112429, 37384.71920221961, 38172.80913595414, 39840.800795995834, 63390.366096340294, 64152.358476416506, 8816.911830881609, 8989.730308090768, 9117.908820911705, 9284.58219380116, 9341.626334946526, 10111.905082917787, 11193.328400296008, 13209.471621135048, 13343.266120363473, 13364.866351336363, 8469.74590762278, 8855.0, 9070.455772653662, 9375.624975000923, 10278.33190842593, 11370.484552975011, 11532.711682208012, 11594.304341739522, 11600.245984011011, 11930.522779089086, 11983.700407489883, 12073.215241009306, 12174.330411827434, 13150.27673477968, 13154.079214454896, 9430.0, 9830.164436023018, 10104.84842727345, 10156.187504999434, 10385.480725963822, 10235.283530152818, 10269.229807764597, 10410.739731506774, 10504.369737815756, 10619.30974486656], "value": 6391.065695920613, "unit": "score"}], "name": "dromaeo_css", "value": 6269.235648367845, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 114.0, 116.0, 117.0, 115.0, 120.0, 116.0, 115.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 128.0, 151.0, 185.0, 128.0, 151.0, 148.0, 129.0, 186.0, 121.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 160.0, 162.0, 162.0, 161.0, 161.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 168.0, 75.0, 75.0, 76.0, 121.0, 108.0, 79.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 146.0, 86.0, 86.0, 86.0, 141.0, 85.0, 85.0, 136.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 120.0, 118.0, 117.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 150.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 67.0, 67.0, 66.0, 66.0, 69.0, 66.0, 67.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 68.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 84.0, 81.0, 91.0, 84.0, 83.0, 82.0, 92.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 142.0, 129.0, 134.0, 132.0, 133.0, 133.0, 133.0, 150.0, 133.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 182.0, 177.0, 173.0, 176.0, 177.0, 170.0, 174.0, 175.0, 177.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 69.0, 70.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1573.6000000000004, "alertThreshold": 2.0}]} 20:29:27 INFO - Return code: 0 20:29:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:29:27 INFO - # TBPL SUCCESS # 20:29:27 INFO - Running post-action listener: _resource_record_post_action 20:29:27 INFO - [mozharness: 2016-08-22 03:29:27.546207Z] Finished run-tests step (success) 20:29:27 INFO - Running post-run listener: _resource_record_post_run 20:29:27 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406269952; Read time: 12; Write time: 231336 20:29:27 INFO - TinderboxPrint: CPU usage
13.2% 20:29:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 20:29:27 INFO - TinderboxPrint: I/O write bytes / time
406,269,952 / 231,336 20:29:27 INFO - TinderboxPrint: CPU idle
8,325.8 (86.7%) 20:29:27 INFO - TinderboxPrint: CPU user
1,206.2 (12.6%) 20:29:27 INFO - TinderboxPrint: Swap in / out
0 / 0 20:29:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114233344; Read time: 0; Write time: 82216 20:29:27 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 292036608; Read time: 0; Write time: 149120 20:29:28 INFO - Running post-run listener: _upload_blobber_files 20:29:28 INFO - Blob upload gear active. 20:29:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:29:28 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 20:29:28 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:29:28 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:29:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:29:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:29:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:32 INFO - (blobuploader) - INFO - Done attempting. 20:29:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:29:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:33 INFO - (blobuploader) - INFO - Done attempting. 20:29:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:29:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:29:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:34 INFO - (blobuploader) - INFO - Done attempting. 20:29:34 INFO - (blobuploader) - INFO - Iteration through files over. 20:29:34 INFO - Return code: 0 20:29:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:29:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:29:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3c41b4ddf034727fba5dab94f50b109245f931ecc3293e8b1572a0492e2f0f81f7a2076b131122f41f1cc2f3d5c2701284ffbba1991220e23576c0c99c940f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b0876dcbe5fa0dd761e027809bbd684b5db46078dde61f2ce2f0d474d64d99a71ef8e28ae017d232757119f4ad226b6ec0c793a3d86a5bf89cbd73238cb30b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/598415c099ae20b8a61832b395efd9726136905e515288d5e9d3bff12e65d70d07944a5184766b30b6ac3fc3ddcefa43456f0f108bc24e63aead640cdd8bce48"} 20:29:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:29:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:29:34 INFO - Contents: 20:29:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3c41b4ddf034727fba5dab94f50b109245f931ecc3293e8b1572a0492e2f0f81f7a2076b131122f41f1cc2f3d5c2701284ffbba1991220e23576c0c99c940f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b0876dcbe5fa0dd761e027809bbd684b5db46078dde61f2ce2f0d474d64d99a71ef8e28ae017d232757119f4ad226b6ec0c793a3d86a5bf89cbd73238cb30b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/598415c099ae20b8a61832b395efd9726136905e515288d5e9d3bff12e65d70d07944a5184766b30b6ac3fc3ddcefa43456f0f108bc24e63aead640cdd8bce48"} 20:29:34 INFO - Running post-run listener: copy_logs_to_upload_dir 20:29:34 INFO - Copying logs to upload dir... 20:29:34 INFO - mkdir: /builds/slave/test/build/upload/logs 20:29:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.317853 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-21 20:29:34.289186) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 20:29:34.292476) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3c41b4ddf034727fba5dab94f50b109245f931ecc3293e8b1572a0492e2f0f81f7a2076b131122f41f1cc2f3d5c2701284ffbba1991220e23576c0c99c940f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b0876dcbe5fa0dd761e027809bbd684b5db46078dde61f2ce2f0d474d64d99a71ef8e28ae017d232757119f4ad226b6ec0c793a3d86a5bf89cbd73238cb30b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/598415c099ae20b8a61832b395efd9726136905e515288d5e9d3bff12e65d70d07944a5184766b30b6ac3fc3ddcefa43456f0f108bc24e63aead640cdd8bce48"} build_url:https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011437 build_url: 'https://queue.taskcluster.net/v1/task/Hu2IU3diSvGqiafNoPCjcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3c41b4ddf034727fba5dab94f50b109245f931ecc3293e8b1572a0492e2f0f81f7a2076b131122f41f1cc2f3d5c2701284ffbba1991220e23576c0c99c940f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b0876dcbe5fa0dd761e027809bbd684b5db46078dde61f2ce2f0d474d64d99a71ef8e28ae017d232757119f4ad226b6ec0c793a3d86a5bf89cbd73238cb30b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/598415c099ae20b8a61832b395efd9726136905e515288d5e9d3bff12e65d70d07944a5184766b30b6ac3fc3ddcefa43456f0f108bc24e63aead640cdd8bce48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-21 20:29:34.323704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:29:34.324097) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471835321.921762-1553842459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-21 20:29:34.409422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 20:29:34.410339) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-21 20:29:34.411123) ========= ========= Total master_lag: 0.18 =========