builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1471399731.15 results: success (0) buildid: 20160816163003 builduid: a224ef2e79104ecfbea302483e87e88d revision: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.145860) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.146379) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.146678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.315498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.315895) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 19:08:51-- 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% 17.6M=0.001s 2016-08-16 19:08:51 (17.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120843 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.460052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.460423) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022588 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.522114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:51.522487) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14974aa2862f2dd9fac16cd78cd88a6208c951d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 14974aa2862f2dd9fac16cd78cd88a6208c951d6 --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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 19:08:51,791 truncating revision to first 12 chars 2016-08-16 19:08:51,791 Setting DEBUG logging. 2016-08-16 19:08:51,791 attempt 1/10 2016-08-16 19:08:51,791 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14974aa2862f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 19:08:52,145 unpacking tar archive at: mozilla-central-14974aa2862f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.732982 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:52.270098) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:52.270470) ========= script_repo_revision: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:52.270900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:52.271226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 19:08:52.283018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-16 19:08:52.283456) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:08:52 INFO - MultiFileLogger online at 20160816 19:08:52 in /builds/slave/test 19:08:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:08:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:52 INFO - {'append_to_log': False, 19:08:52 INFO - 'base_work_dir': '/builds/slave/test', 19:08:52 INFO - 'blob_upload_branch': 'Firefox', 19:08:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:52 INFO - 'branch': 'Firefox', 19:08:52 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:52 INFO - 'config_files': ('talos/linux_config.py',), 19:08:52 INFO - 'default_actions': ('clobber', 19:08:52 INFO - 'read-buildbot-config', 19:08:52 INFO - 'download-and-extract', 19:08:52 INFO - 'populate-webroot', 19:08:52 INFO - 'create-virtualenv', 19:08:52 INFO - 'install', 19:08:52 INFO - 'run-tests'), 19:08:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:52 INFO - 'download_minidump_stackwalk': True, 19:08:52 INFO - 'download_symbols': 'ondemand', 19:08:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:52 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:52 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:52 INFO - 'installer_path': 'installer.exe', 19:08:52 INFO - 'log_level': 'info', 19:08:52 INFO - 'log_name': 'talos', 19:08:52 INFO - 'log_to_console': True, 19:08:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:08:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:08:52 INFO - 'opt_config_files': (), 19:08:52 INFO - 'pip_index': False, 19:08:52 INFO - 'sps_profile': False, 19:08:52 INFO - 'sps_profile_interval': 0, 19:08:52 INFO - 'suite': 'dromaeojs', 19:08:52 INFO - 'system_bits': '32', 19:08:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:52 INFO - 'title': 'talos-linux64-ix-023', 19:08:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:52 INFO - 'use_talos_json': True, 19:08:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:52 INFO - 'work_dir': 'build'} 19:08:52 INFO - [mozharness: 2016-08-17 02:08:52.646771Z] Running clobber step. 19:08:52 INFO - Running pre-action listener: _resource_record_pre_action 19:08:52 INFO - Running main action method: clobber 19:08:52 INFO - rmtree: /builds/slave/test/build 19:08:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:53 INFO - Running post-action listener: _resource_record_post_action 19:08:53 INFO - [mozharness: 2016-08-17 02:08:53.103484Z] Finished clobber step (success) 19:08:53 INFO - [mozharness: 2016-08-17 02:08:53.103585Z] Running read-buildbot-config step. 19:08:53 INFO - Running pre-action listener: _resource_record_pre_action 19:08:53 INFO - Running main action method: read_buildbot_config 19:08:53 INFO - Using buildbot properties: 19:08:53 INFO - { 19:08:53 INFO - "project": "", 19:08:53 INFO - "product": "firefox", 19:08:53 INFO - "script_repo_revision": "production", 19:08:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:08:53 INFO - "repository": "", 19:08:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:08:53 INFO - "buildid": "20160816163003", 19:08:53 INFO - "pgo_build": "True", 19:08:53 INFO - "basedir": "/builds/slave/test", 19:08:53 INFO - "buildnumber": 529, 19:08:53 INFO - "slavename": "talos-linux64-ix-023", 19:08:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:08:53 INFO - "platform": "ubuntu64_hw", 19:08:53 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:08:53 INFO - "branch": "mozilla-central", 19:08:53 INFO - "repo_path": "mozilla-central", 19:08:53 INFO - "revision": "14974aa2862f2dd9fac16cd78cd88a6208c951d6", 19:08:53 INFO - "stage_platform": "linux64-pgo", 19:08:53 INFO - "builduid": "a224ef2e79104ecfbea302483e87e88d", 19:08:53 INFO - "slavebuilddir": "test" 19:08:53 INFO - } 19:08:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:08:53 INFO - Running post-action listener: _resource_record_post_action 19:08:53 INFO - [mozharness: 2016-08-17 02:08:53.106263Z] Finished read-buildbot-config step (success) 19:08:53 INFO - [mozharness: 2016-08-17 02:08:53.106352Z] Running download-and-extract step. 19:08:53 INFO - Running pre-action listener: _resource_record_pre_action 19:08:53 INFO - Running main action method: download_and_extract 19:08:53 INFO - mkdir: /builds/slave/test/build/tests 19:08:53 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')]} 19:08:53 INFO - trying https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:08:53 INFO - Downloading https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:53 INFO - Downloaded 1361 bytes. 19:08:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:08:53 INFO - Using the following test package requirements: 19:08:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:08:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:08:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'jsshell-linux-x86_64.zip'], 19:08:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:08:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:08:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:08:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:08:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:08:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:08:53 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')]} 19:08:53 INFO - trying https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:08:53 INFO - Downloading https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:54 INFO - Downloaded 21838847 bytes. 19:08:54 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 19:08:59 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 19:08:59 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')]} 19:08:59 INFO - trying https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:08:59 INFO - Downloaded 21838847 bytes. 19:08:59 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 19: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')]} 19:09:04 INFO - trying https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:04 INFO - Downloading https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:09:05 INFO - Downloaded 14413719 bytes. 19:09:05 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 19:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:06 INFO - trying https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:09:06 INFO - Downloading https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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 19:09:07 INFO - Downloaded 59416577 bytes. 19:09:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:09:07 INFO - mkdir: /builds/slave/test/properties 19:09:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:07 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:07 INFO - Contents: 19:09:07 INFO - build_url:https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:09:08 INFO - Running post-action listener: _resource_record_post_action 19:09:08 INFO - Running post-action listener: set_extra_try_arguments 19:09:08 INFO - [mozharness: 2016-08-17 02:09:08.570888Z] Finished download-and-extract step (success) 19:09:08 INFO - [mozharness: 2016-08-17 02:09:08.571111Z] Running populate-webroot step. 19:09:08 INFO - Running pre-action listener: _resource_record_pre_action 19:09:08 INFO - Running main action method: populate_webroot 19:09:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:08 INFO - '--tppagecycles', 19:09:08 INFO - '7'], 19:09:08 INFO - 'tests': ['tsvgm']}}, 19:09:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['damp', 'tps']}, 19:09:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'tests': ['damp', 'tps']}, 19:09:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['dromaeo_dom']}, 19:09:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['basic_compositor_video']}, 19:09:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:09:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['a11yr', 19:09:08 INFO - 'ts_paint', 19:09:08 INFO - 'tpaint', 19:09:08 INFO - 'sessionrestore', 19:09:08 INFO - 'sessionrestore_no_auto_restore', 19:09:08 INFO - 'tabpaint']}, 19:09:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['tsvgx', 19:09:08 INFO - 'tsvgr_opacity', 19:09:08 INFO - 'tart', 19:09:08 INFO - 'tscrollx', 19:09:08 INFO - 'cart']}, 19:09:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:08 INFO - 'tsvgr_opacity', 19:09:08 INFO - 'tart', 19:09:08 INFO - 'tscrollx', 19:09:08 INFO - 'cart']}, 19:09:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'talos_options': ['--disable-e10s'], 19:09:08 INFO - 'tests': ['tp5o']}, 19:09:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'tests': ['tp5o']}, 19:09:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'talos_options': ['--disable-e10s', 19:09:08 INFO - '--xperf_path', 19:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:08 INFO - 'tests': ['tp5n']}, 19:09:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:08 INFO - 'talos_options': ['--xperf_path', 19:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:08 INFO - 'tests': ['tp5n']}}, 19:09:08 INFO - 'talos.zip': {'path': '', 19:09:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:08 INFO - Running post-action listener: _resource_record_post_action 19:09:08 INFO - [mozharness: 2016-08-17 02:09:08.590935Z] Finished populate-webroot step (success) 19:09:08 INFO - [mozharness: 2016-08-17 02:09:08.591023Z] Running create-virtualenv step. 19:09:08 INFO - Running pre-action listener: _resource_record_pre_action 19:09:08 INFO - Running main action method: create_virtualenv 19:09:08 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:09:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:08 INFO - Using real prefix '/usr' 19:09:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:09 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:11 INFO - Installing pip.................done. 19:09:11 INFO - Return code: 0 19:09:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:11 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:11 INFO - Using env: {'DISPLAY': ':0', 19:09:11 INFO - 'HOME': '/home/cltbld', 19:09:11 INFO - 'LANG': 'en_US.UTF-8', 19:09:11 INFO - 'LANGUAGE': 'en_US:en', 19:09:11 INFO - 'LOGNAME': 'cltbld', 19:09:11 INFO - 'MAIL': '/var/mail/cltbld', 19:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:11 INFO - 'MOZ_NO_REMOTE': '1', 19:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:11 INFO - 'NO_EM_RESTART': '1', 19:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:11 INFO - 'PWD': '/builds/slave/test', 19:09:11 INFO - 'SHELL': '/bin/bash', 19:09:11 INFO - 'SHLVL': '1', 19:09:11 INFO - 'TERM': 'linux', 19:09:11 INFO - 'TMOUT': '86400', 19:09:11 INFO - 'USER': 'cltbld', 19:09:11 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:11 INFO - '_': '/tools/buildbot/bin/python'} 19:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:09:11 INFO - Cleaning up... 19:09:11 INFO - Return code: 0 19:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:11 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:09:11 INFO - Using env: {'DISPLAY': ':0', 19:09:11 INFO - 'HOME': '/home/cltbld', 19:09:11 INFO - 'LANG': 'en_US.UTF-8', 19:09:11 INFO - 'LANGUAGE': 'en_US:en', 19:09:11 INFO - 'LOGNAME': 'cltbld', 19:09:11 INFO - 'MAIL': '/var/mail/cltbld', 19:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:11 INFO - 'MOZ_NO_REMOTE': '1', 19:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:11 INFO - 'NO_EM_RESTART': '1', 19:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:11 INFO - 'PWD': '/builds/slave/test', 19:09:11 INFO - 'SHELL': '/bin/bash', 19:09:11 INFO - 'SHLVL': '1', 19:09:11 INFO - 'TERM': 'linux', 19:09:11 INFO - 'TMOUT': '86400', 19:09:11 INFO - 'USER': 'cltbld', 19:09:11 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:11 INFO - '_': '/tools/buildbot/bin/python'} 19:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:11 INFO - Downloading/unpacking psutil>=3.1.1 19:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:13 INFO - Installing collected packages: psutil 19:09:13 INFO - Running setup.py install for psutil 19:09:13 INFO - building 'psutil._psutil_linux' extension 19:09:13 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 19:09:13 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 19:09:13 INFO - building 'psutil._psutil_posix' extension 19:09:13 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 19:09:14 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 19:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:14 INFO - Successfully installed psutil 19:09:14 INFO - Cleaning up... 19:09:14 INFO - Return code: 0 19:09:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:14 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')]} 19:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:14 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')]} 19:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:14 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:09:14 INFO - Using env: {'DISPLAY': ':0', 19:09:14 INFO - 'HOME': '/home/cltbld', 19:09:14 INFO - 'LANG': 'en_US.UTF-8', 19:09:14 INFO - 'LANGUAGE': 'en_US:en', 19:09:14 INFO - 'LOGNAME': 'cltbld', 19:09:14 INFO - 'MAIL': '/var/mail/cltbld', 19:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:14 INFO - 'MOZ_NO_REMOTE': '1', 19:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:14 INFO - 'NO_EM_RESTART': '1', 19:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:14 INFO - 'PWD': '/builds/slave/test', 19:09:14 INFO - 'SHELL': '/bin/bash', 19:09:14 INFO - 'SHLVL': '1', 19:09:14 INFO - 'TERM': 'linux', 19:09:14 INFO - 'TMOUT': '86400', 19:09:14 INFO - 'USER': 'cltbld', 19:09:14 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:14 INFO - '_': '/tools/buildbot/bin/python'} 19:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 19: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 19: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 19: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 19: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 19: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 19: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 19:09:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:16 INFO - Installing collected packages: mozsystemmonitor 19:09:16 INFO - Running setup.py install for mozsystemmonitor 19:09:16 INFO - Successfully installed mozsystemmonitor 19:09:16 INFO - Cleaning up... 19:09:16 INFO - Return code: 0 19:09:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:16 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:09:16 INFO - Using env: {'DISPLAY': ':0', 19:09:16 INFO - 'HOME': '/home/cltbld', 19:09:16 INFO - 'LANG': 'en_US.UTF-8', 19:09:16 INFO - 'LANGUAGE': 'en_US:en', 19:09:16 INFO - 'LOGNAME': 'cltbld', 19:09:16 INFO - 'MAIL': '/var/mail/cltbld', 19:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:16 INFO - 'MOZ_NO_REMOTE': '1', 19:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:16 INFO - 'NO_EM_RESTART': '1', 19:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:16 INFO - 'PWD': '/builds/slave/test', 19:09:16 INFO - 'SHELL': '/bin/bash', 19:09:16 INFO - 'SHLVL': '1', 19:09:16 INFO - 'TERM': 'linux', 19:09:16 INFO - 'TMOUT': '86400', 19:09:16 INFO - 'USER': 'cltbld', 19:09:16 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:16 INFO - '_': '/tools/buildbot/bin/python'} 19:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:16 INFO - Downloading/unpacking jsonschema==2.5.1 19:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:18 INFO - warning: no files found matching '*.txt' 19:09:18 INFO - no previously-included directories found matching 'build' 19:09:18 INFO - no previously-included directories found matching 'dist' 19:09:18 INFO - no previously-included directories found matching '.git*' 19:09:18 INFO - Installing collected packages: jsonschema, functools32 19:09:18 INFO - Running setup.py install for functools32 19:09:18 INFO - warning: no files found matching '*.txt' 19:09:19 INFO - no previously-included directories found matching 'build' 19:09:19 INFO - no previously-included directories found matching 'dist' 19:09:19 INFO - no previously-included directories found matching '.git*' 19:09:19 INFO - Successfully installed jsonschema functools32 19:09:19 INFO - Cleaning up... 19:09:19 INFO - Return code: 0 19:09:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:19 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:19 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 19:09:19 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 19:09:19 INFO - Using env: {'DISPLAY': ':0', 19:09:19 INFO - 'HOME': '/home/cltbld', 19:09:19 INFO - 'LANG': 'en_US.UTF-8', 19:09:19 INFO - 'LANGUAGE': 'en_US:en', 19:09:19 INFO - 'LOGNAME': 'cltbld', 19:09:19 INFO - 'MAIL': '/var/mail/cltbld', 19:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:19 INFO - 'MOZ_NO_REMOTE': '1', 19:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:19 INFO - 'NO_EM_RESTART': '1', 19:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:19 INFO - 'PWD': '/builds/slave/test', 19:09:19 INFO - 'SHELL': '/bin/bash', 19:09:19 INFO - 'SHLVL': '1', 19:09:19 INFO - 'TERM': 'linux', 19:09:19 INFO - 'TMOUT': '86400', 19:09:19 INFO - 'USER': 'cltbld', 19:09:19 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:19 INFO - '_': '/tools/buildbot/bin/python'} 19:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:09:19 INFO - Cleaning up... 19:09:19 INFO - Return code: 0 19:09:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:19 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:19 INFO - Using env: {'DISPLAY': ':0', 19:09:19 INFO - 'HOME': '/home/cltbld', 19:09:19 INFO - 'LANG': 'en_US.UTF-8', 19:09:19 INFO - 'LANGUAGE': 'en_US:en', 19:09:19 INFO - 'LOGNAME': 'cltbld', 19:09:19 INFO - 'MAIL': '/var/mail/cltbld', 19:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:19 INFO - 'MOZ_NO_REMOTE': '1', 19:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:19 INFO - 'NO_EM_RESTART': '1', 19:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:19 INFO - 'PWD': '/builds/slave/test', 19:09:19 INFO - 'SHELL': '/bin/bash', 19:09:19 INFO - 'SHLVL': '1', 19:09:19 INFO - 'TERM': 'linux', 19:09:19 INFO - 'TMOUT': '86400', 19:09:19 INFO - 'USER': 'cltbld', 19:09:19 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:19 INFO - '_': '/tools/buildbot/bin/python'} 19:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:22 INFO - Downloading docopt-0.6.1.tar.gz 19:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:22 INFO - Running setup.py install for blobuploader 19:09:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:22 INFO - Running setup.py install for requests 19:09:22 INFO - Running setup.py install for docopt 19:09:22 INFO - Successfully installed blobuploader requests docopt 19:09:22 INFO - Cleaning up... 19:09:22 INFO - Return code: 0 19:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:22 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:22 INFO - Using env: {'DISPLAY': ':0', 19:09:22 INFO - 'HOME': '/home/cltbld', 19:09:22 INFO - 'LANG': 'en_US.UTF-8', 19:09:22 INFO - 'LANGUAGE': 'en_US:en', 19:09:22 INFO - 'LOGNAME': 'cltbld', 19:09:22 INFO - 'MAIL': '/var/mail/cltbld', 19:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:22 INFO - 'MOZ_NO_REMOTE': '1', 19:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:22 INFO - 'NO_EM_RESTART': '1', 19:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:22 INFO - 'PWD': '/builds/slave/test', 19:09:22 INFO - 'SHELL': '/bin/bash', 19:09:22 INFO - 'SHLVL': '1', 19:09:22 INFO - 'TERM': 'linux', 19:09:22 INFO - 'TMOUT': '86400', 19:09:22 INFO - 'USER': 'cltbld', 19:09:22 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:22 INFO - '_': '/tools/buildbot/bin/python'} 19:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:23 INFO - Running setup.py (path:/tmp/pip-0xPW3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:23 INFO - Running setup.py (path:/tmp/pip-K6R4Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:23 INFO - Running setup.py (path:/tmp/pip-gYX4fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:23 INFO - Running setup.py (path:/tmp/pip-a9JRz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:23 INFO - Running setup.py (path:/tmp/pip-oyQWsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:23 INFO - Running setup.py (path:/tmp/pip-wGmE41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:23 INFO - Running setup.py (path:/tmp/pip-GWEhoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:23 INFO - Running setup.py (path:/tmp/pip-nHiaKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:23 INFO - Running setup.py (path:/tmp/pip-dix9ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:23 INFO - Running setup.py (path:/tmp/pip-_c4_xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:23 INFO - Running setup.py (path:/tmp/pip-vZXnCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:23 INFO - Running setup.py (path:/tmp/pip-PdZAkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:23 INFO - Running setup.py (path:/tmp/pip-NKqcLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:24 INFO - Running setup.py (path:/tmp/pip-arid9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:24 INFO - Running setup.py (path:/tmp/pip-oL61pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:24 INFO - Running setup.py (path:/tmp/pip-7VbhzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:24 INFO - Running setup.py (path:/tmp/pip-uFhFNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:24 INFO - Running setup.py install for manifestparser 19:09:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:24 INFO - Running setup.py install for mozcrash 19:09:24 INFO - Running setup.py install for mozdebug 19:09:24 INFO - Running setup.py install for mozdevice 19:09:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:24 INFO - Running setup.py install for mozfile 19:09:24 INFO - Running setup.py install for mozhttpd 19:09:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:24 INFO - Running setup.py install for mozinfo 19:09:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for mozInstall 19:09:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for mozleak 19:09:25 INFO - Running setup.py install for mozlog 19:09:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for moznetwork 19:09:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for mozprocess 19:09:25 INFO - Running setup.py install for mozprofile 19:09:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for mozrunner 19:09:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Running setup.py install for mozscreenshot 19:09:25 INFO - Running setup.py install for moztest 19:09:26 INFO - Running setup.py install for mozversion 19:09:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:26 INFO - Cleaning up... 19:09:26 INFO - Return code: 0 19:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:26 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')]} 19:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:26 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')]} 19:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:26 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:26 INFO - Using env: {'DISPLAY': ':0', 19:09:26 INFO - 'HOME': '/home/cltbld', 19:09:26 INFO - 'LANG': 'en_US.UTF-8', 19:09:26 INFO - 'LANGUAGE': 'en_US:en', 19:09:26 INFO - 'LOGNAME': 'cltbld', 19:09:26 INFO - 'MAIL': '/var/mail/cltbld', 19:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:26 INFO - 'MOZ_NO_REMOTE': '1', 19:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:26 INFO - 'NO_EM_RESTART': '1', 19:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:26 INFO - 'PWD': '/builds/slave/test', 19:09:26 INFO - 'SHELL': '/bin/bash', 19:09:26 INFO - 'SHLVL': '1', 19:09:26 INFO - 'TERM': 'linux', 19:09:26 INFO - 'TMOUT': '86400', 19:09:26 INFO - 'USER': 'cltbld', 19:09:26 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:26 INFO - '_': '/tools/buildbot/bin/python'} 19:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:26 INFO - Running setup.py (path:/tmp/pip-K8MEoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:26 INFO - Running setup.py (path:/tmp/pip-2WLF3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:26 INFO - Running setup.py (path:/tmp/pip-Wtrfe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:26 INFO - Running setup.py (path:/tmp/pip-1gCxdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:26 INFO - Running setup.py (path:/tmp/pip-jd_Pwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:26 INFO - Running setup.py (path:/tmp/pip-VEZdn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:26 INFO - Running setup.py (path:/tmp/pip-f1hVIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:26 INFO - Running setup.py (path:/tmp/pip-nyKm4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:26 INFO - Running setup.py (path:/tmp/pip-QSmMF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:27 INFO - Running setup.py (path:/tmp/pip-rZxMxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:27 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)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:27 INFO - Running setup.py (path:/tmp/pip-cxrNeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:27 INFO - Running setup.py (path:/tmp/pip-hoZ4WT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:27 INFO - Running setup.py (path:/tmp/pip-zpE_JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:27 INFO - Running setup.py (path:/tmp/pip-Tw7ULH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:27 INFO - Running setup.py (path:/tmp/pip-S3TM0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:27 INFO - Running setup.py (path:/tmp/pip-PvNCp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:27 INFO - Running setup.py (path:/tmp/pip-_AGGXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:29 INFO - Downloading blessings-1.6.tar.gz 19:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:29 INFO - Installing collected packages: blessings 19:09:29 INFO - Running setup.py install for blessings 19:09:29 INFO - Successfully installed blessings 19:09:29 INFO - Cleaning up... 19:09:29 INFO - Return code: 0 19:09:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:29 INFO - Reading from file tmpfile_stdout 19:09:29 INFO - Current package versions: 19:09:29 INFO - argparse == 1.2.1 19:09:29 INFO - blessings == 1.6 19:09:29 INFO - blobuploader == 1.2.4 19:09:29 INFO - docopt == 0.6.1 19:09:29 INFO - functools32 == 3.2.3-2 19:09:29 INFO - jsonschema == 2.5.1 19:09:29 INFO - manifestparser == 1.1 19:09:29 INFO - mozInstall == 1.12 19:09:29 INFO - mozcrash == 0.17 19:09:29 INFO - mozdebug == 0.1 19:09:29 INFO - mozdevice == 0.48 19:09:29 INFO - mozfile == 1.2 19:09:29 INFO - mozhttpd == 0.7 19:09:29 INFO - mozinfo == 0.9 19:09:29 INFO - mozleak == 0.1 19:09:29 INFO - mozlog == 3.3 19:09:29 INFO - moznetwork == 0.27 19:09:29 INFO - mozprocess == 0.23 19:09:29 INFO - mozprofile == 0.28 19:09:29 INFO - mozrunner == 6.12 19:09:29 INFO - mozscreenshot == 0.1 19:09:29 INFO - mozsystemmonitor == 0.3 19:09:29 INFO - moztest == 0.7 19:09:29 INFO - mozversion == 1.4 19:09:29 INFO - psutil == 3.1.1 19:09:29 INFO - requests == 1.2.3 19:09:29 INFO - wsgiref == 0.1.2 19:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 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 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:29 INFO - Using env: {'DISPLAY': ':0', 19:09:29 INFO - 'HOME': '/home/cltbld', 19:09:29 INFO - 'LANG': 'en_US.UTF-8', 19:09:29 INFO - 'LANGUAGE': 'en_US:en', 19:09:29 INFO - 'LOGNAME': 'cltbld', 19:09:29 INFO - 'MAIL': '/var/mail/cltbld', 19:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:29 INFO - 'MOZ_NO_REMOTE': '1', 19:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:29 INFO - 'NO_EM_RESTART': '1', 19:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:29 INFO - 'PWD': '/builds/slave/test', 19:09:29 INFO - 'SHELL': '/bin/bash', 19:09:29 INFO - 'SHLVL': '1', 19:09:29 INFO - 'TERM': 'linux', 19:09:29 INFO - 'TMOUT': '86400', 19:09:29 INFO - 'USER': 'cltbld', 19:09:29 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:29 INFO - '_': '/tools/buildbot/bin/python'} 19:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:30 INFO - Cleaning up... 19:09:30 INFO - Return code: 0 19:09:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa11f7b8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1343850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, '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': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', '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 19:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:09:30 INFO - Using env: {'DISPLAY': ':0', 19:09:30 INFO - 'HOME': '/home/cltbld', 19:09:30 INFO - 'LANG': 'en_US.UTF-8', 19:09:30 INFO - 'LANGUAGE': 'en_US:en', 19:09:30 INFO - 'LOGNAME': 'cltbld', 19:09:30 INFO - 'MAIL': '/var/mail/cltbld', 19:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:30 INFO - 'MOZ_NO_REMOTE': '1', 19:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:30 INFO - 'NO_EM_RESTART': '1', 19:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:30 INFO - 'PWD': '/builds/slave/test', 19:09:30 INFO - 'SHELL': '/bin/bash', 19:09:30 INFO - 'SHLVL': '1', 19:09:30 INFO - 'TERM': 'linux', 19:09:30 INFO - 'TMOUT': '86400', 19:09:30 INFO - 'USER': 'cltbld', 19:09:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:30 INFO - '_': '/tools/buildbot/bin/python'} 19:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:09:30 INFO - Cleaning up... 19:09:30 INFO - Return code: 0 19:09:30 INFO - Running post-action listener: _resource_record_post_action 19:09:30 INFO - Running post-action listener: _start_resource_monitoring 19:09:30 INFO - Starting resource monitoring. 19:09:30 INFO - [mozharness: 2016-08-17 02:09:30.404579Z] Finished create-virtualenv step (success) 19:09:30 INFO - [mozharness: 2016-08-17 02:09:30.405041Z] Running install step. 19:09:30 INFO - Running pre-action listener: _resource_record_pre_action 19:09:30 INFO - Running main action method: install 19:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:30 INFO - Reading from file tmpfile_stdout 19:09:30 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:30 INFO - Reading from file tmpfile_stdout 19:09:30 INFO - Output received: 19:09:30 INFO - Usage: mozinstall [options] installer 19:09:30 INFO - Options: 19:09:30 INFO - -h, --help show this help message and exit 19:09:30 INFO - -d DEST, --destination=DEST 19:09:30 INFO - Directory to install application into. [default: 19:09:30 INFO - "/builds/slave/test"] 19:09:30 INFO - --app=APP Application being installed. [default: firefox] 19:09:30 INFO - mkdir: /builds/slave/test/build/application 19:09:30 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'] 19:09:30 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 19:09:48 INFO - Reading from file tmpfile_stdout 19:09:48 INFO - Output received: 19:09:48 INFO - /builds/slave/test/build/application/firefox/firefox 19:09:48 INFO - Running post-action listener: _resource_record_post_action 19:09:48 INFO - [mozharness: 2016-08-17 02:09:48.352683Z] Finished install step (success) 19:09:48 INFO - [mozharness: 2016-08-17 02:09:48.352930Z] Running run-tests step. 19:09:48 INFO - Running pre-action listener: _resource_record_pre_action 19:09:48 INFO - Running main action method: run_tests 19:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:48 INFO - Python 2.7.3 19:09:48 INFO - Return code: 0 19:09:48 INFO - grabbing minidump binary from tooltool 19:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:48 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 0x133e370>, '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 0x13418f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:48 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 19:09:48 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 19:09:48 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 19:09:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:48 INFO - Return code: 0 19:09:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:09:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:09:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:09:48 INFO - Using env: {'DISPLAY': ':0', 19:09:48 INFO - 'HOME': '/home/cltbld', 19:09:48 INFO - 'LANG': 'en_US.UTF-8', 19:09:48 INFO - 'LANGUAGE': 'en_US:en', 19:09:48 INFO - 'LOGNAME': 'cltbld', 19:09:48 INFO - 'MAIL': '/var/mail/cltbld', 19:09:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:48 INFO - 'MOZ_NO_REMOTE': '1', 19:09:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:48 INFO - 'NO_EM_RESTART': '1', 19:09:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:48 INFO - 'PWD': '/builds/slave/test', 19:09:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:48 INFO - 'SHELL': '/bin/bash', 19:09:48 INFO - 'SHLVL': '1', 19:09:48 INFO - 'TERM': 'linux', 19:09:48 INFO - 'TMOUT': '86400', 19:09:48 INFO - 'USER': 'cltbld', 19:09:48 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213', 19:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:48 INFO - '_': '/tools/buildbot/bin/python'} 19:09:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:09:48 INFO - mozversion application_buildid: 20160816163003 19:09:48 INFO - mozversion application_changeset: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 19:09:48 INFO - mozversion application_display_name: Nightly 19:09:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:48 INFO - mozversion application_name: Firefox 19:09:48 INFO - mozversion application_remotingname: firefox 19:09:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:09:48 INFO - mozversion application_vendor: Mozilla 19:09:48 INFO - mozversion application_version: 51.0a1 19:09:48 INFO - mozversion platform_buildid: 20160816163003 19:09:48 INFO - mozversion platform_changeset: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 19:09:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:09:48 INFO - mozversion platform_version: 51.0a1 19:09:48 INFO - using testdate: 1471399788 19:09:48 INFO - actual date: 1471399788 19:09:48 INFO - starting webserver on 'localhost:40786' 19:09:48 INFO - SUITE-START | Running 2 tests 19:09:48 INFO - TEST-START | dromaeo_css 19:09:48 INFO - operating with platform_type : linux_ 19:09:48 INFO - Initialising browser for dromaeo_css test... 19:09:48 INFO - TEST-INFO | started process 31968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK6Yx1w/profile http://localhost:40786/getInfo.html) 19:09:52 INFO - PROCESS | 31968 | __metrics Screen width/height:1600/1200 19:09:52 INFO - PROCESS | 31968 | colorDepth:24 19:09:52 INFO - PROCESS | 31968 | Browser inner width/height: 1024/697 19:09:52 INFO - PROCESS | 31968 | __metrics 19:09:53 INFO - PROCESS | 31968 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:09:53 INFO - PROCESS | 31968 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:09:54 INFO - TEST-INFO | 31968: exit 0 19:09:54 INFO - Browser initialized. 19:09:54 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:54 INFO - TEST-INFO | started process 32048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK6Yx1w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:06 INFO - PROCESS | 32048 | RSS: Main: 156086272 19:10:06 INFO - PROCESS | 32048 | 19:10:57 INFO - PROCESS | 32048 | 1471399857568 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:13:06 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40786/tests/dromaeo/cssquery-ext.html) 19:13:07 INFO - PROCESS | 32048 | RSS: Main: 256610304 19:13:07 INFO - PROCESS | 32048 | 19:16:10 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-ext.html (next: http://localhost:40786/tests/dromaeo/cssquery-jquery.html) 19:16:10 INFO - PROCESS | 32048 | RSS: Main: 250048512 19:16:10 INFO - PROCESS | 32048 | 19:19:12 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40786/tests/dromaeo/cssquery-mootools.html) 19:19:13 INFO - PROCESS | 32048 | RSS: Main: 243941376 19:19:13 INFO - PROCESS | 32048 | 19:22:15 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40786/tests/dromaeo/cssquery-prototype.html) 19:22:16 INFO - PROCESS | 32048 | RSS: Main: 186228736 19:22:16 INFO - PROCESS | 32048 | 19:25:18 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40786/tests/dromaeo/cssquery-yui.html) 19:25:19 INFO - PROCESS | 32048 | RSS: Main: 185434112 19:25:19 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | Cycle 1(1): loaded http://localhost:40786/tests/dromaeo/cssquery-yui.html (next: http://localhost:40786/tests/dromaeo/cssquery-dojo.html) 19:28:22 INFO - PROCESS | 32048 | RSS: Main: 279834624 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | __start_tp_report 19:28:22 INFO - PROCESS | 32048 | _x_x_mozilla_page_load 19:28:22 INFO - PROCESS | 32048 | _x_x_mozilla_page_load_details 19:28:22 INFO - PROCESS | 32048 | |i|pagename|runs| 19:28:22 INFO - PROCESS | 32048 | |0;dojo.html;3033.742131918787;3052.938941221177;3057.419090372829;3073.047355319851;3080.9229769255767;5166.9224961625605;5223.764930578123;5245.685258884465;5329.3650793650795;5417.2686687297255;5308.654937429064;5318.680879147244;5356.464353564642;5359.5712343012565;5394.406615272317;4770.236762118062;4854.538818812225;4877.317175595402;4897.191963326047;4954.7522623868845;4904.313396124546;4928.408590969088;4941.579965702919;4962.851114466572;4972;4782.091402633523;4817.325574419584;4822.710637361734;4841.2012018016985;4940.382452193476;4776.020915712305;4777.617790576745;4794.832180873687;4960.553550180465;4971.254311853215;4791.592714619262;4823.445303790074;4839.564439200453;4889.119958407486;4890.853274401774;5110.003549307887;5171.405288391846;5301.363836339664;5308.124159513675;5349.465053494659;5563.13771365439;5579.888402231933;5663.348714897757;5668.744906479177;5681.318241811009;5631.9718401408145;5713.657180569179;5715.3998830122655;5716.314042314907;5718.513926316226;5157.999999999963;5254.973725131388;5275.709835959059;5313.149896016657;5329.920051199235;5883;5884;5909.349971503131;5937.762489500372;5941.346451890288;5638.238837756932;5646.153077038477;5665.631733937281;5714.657120572779;5716.342620598602;5481.123020316731;5571.0807716727095;5645.604807663424;5782.335031471434;5791.0734282514595;5692.658440493584;5693.117566777156;5693.743781529841;5728.398518155617;5747.798827041033;5645.068563686946;5685;5689.630174038674;5691.573132015115;5705.1727499512335;4235.639970602471;4322.935155972663;4375.47494300686;4390.4511936008;4432.180046691373;3918.314294998364;3934.6458818706456;3958.980205098956;3975.860844870416;3990.2817492850986;5455.072637651609;5459.754311056012;5464.9180262296095;5468.425815289417;5517.4482551745305;5599.468050535193;5663.9716801415725;5731.512821410143;5735.541156707453;5748.913766293509;5468.863278418072;5480.095784195642;5506.669599824023;5514.09017512114;5548.0845660465575;4968.453470118284;4992.725400102957;4994;4998.475160108209;5046.066477701637;5687.744051517609;5885.941140588539;5978.252718410199;5989.9101513477335;6000.699965001732;5716.142578613241;5811.389804070512;5834.329052159056;5867.706614669164;5877.0302900021015;6001.309849367204;6005.249343832021;6017.608855424383;6027.21646185993;6029.909551356821;5386.48828361305;5551.111822108443;5618.775248990158;5654.123610840326;5658.519025882846;5712.143178523254;5749.913751293818;5778.422157784188;5821.068629019336;5838.095095260242;5628.127640215773;5636.070048441961;5637.3235211774845;5641.689707066151;5643.040683083804;5389.919151212814;5496.835094947158;5589.329280486368;5600.35595906476;5600.831975040755;5509.586780991362;5596.916046259228;5604.971975140098;5638.323401191883;5646.3788983212635;5662;5670.206171136127;5672.688002159921;5682.6306290091015;5686.60193786431;5371.0869152244995;5380.354357476971;5487.972560137174;5497.257870187634;5498.80754173602 19:28:22 INFO - PROCESS | 32048 | |1;ext.html;574.8993926062941;595.6455908734303;602.8214839987825;618.3569088148327;619.334215718103;41448.37827432587;43009.78495107528;43054.13891722167;43081.35377969329;46065.53934460654;45062.774686126606;45459.77270113645;45575.544244557546;45679.771601142034;45715;8975.14736100071;9123;9125.04187060359;9135.451872887616;9135.543222838865;8962.462252264851;9044.502552359618;9055.547222638874;9060.093990600953;9062.275017998545;9069.954650226773;9101.453912765222;9123.315751318645;9127.269818414545;9127.497987610679;9058.139476749731;9072.95463522678;9089.954550227272;9094.226990705798;9103;8922.553872306358;8993.910060899438;8997.59510822008;8998.505082220476;9003.279737620975;42577.36133957993;42578.36132458015;42631.78684106591;42709.786451067856;42974.785126074166;3647.5258216431694;3715.4612581175847;3765.4540091686554;3773.754705944132;4098.979505102456;32153.51769723456;32484.350312993607;32512;32554.511682324784;32640.183995400068;3511;3524.4713293006;3529.4882242075014;3535.681788639009;3582.4268117101137;347531;347588;348578;348764;348781;56318;56400;56538.15192772112;56698;56713.43286567164;35790.463143052875;36922;36976.81511592425;38914;39069;16848.07335596554;17046.403375881786;17066.829331706525;17079.573010674834;17089.658206835793;11188.160887933438;11189.272697274651;11190.608328708619;11191.216614836883;11278.774424511628;19047.523811904535;19107.331243406414;19152.904235478734;19153.80846191548;19181.80818191828;7168;7239.674214660353;7378.778636640909;7419;7460.813479662943;964.5032808103832;1015.8679371681634;1063.3194755356578;1093.4751319366749;1174.4597485156924;32729.509057364157;33356.3328733424;33367.49948750771;33458.16354591155;34136.82931585326;863.0721973878107;1148.3798748675788;1148.5635458525874;1154.543955137716;1169.561414469574;17359.479215623553;17424.738628920575;17450.65098698019;17485.300587976337;17500.387486437914;555742;556692;558123;559504;559806;58352.124718129264;58489;58645.70677146587;58797.412025879195;58872.705636471546;1247.3700781105483;1363.1003537665094;1381.3990913952464;1393.226759148662;1400.7268582626493;31905.68094319027;33038;33866.8306658475;33946.49080263748;34388.48417273793;50122.49877501178;50516;50569.494305056476;50599.49400505948;50626;413060;421190;421307;423590;501723;43617;44497;44574;45193;45465.77267113643;47485;48613.51386486231;48739;48812;50170.24744628907;41352.79323603483;41939.16121677488;42157.78921105375;42228.36657450079;43926.78036609796;41928.79035604924;41994;42014;43472;43794.781026094664;41812.58187418087;42515.57484425116;42684.146317074104;43473;43487.347689784045;34342;37379.81310093432;37953;38131.80934095423;38451.615483845926;28877.7112228875;28953.236079309554;29139.12582622525;29200;29302.26744331409 19:28:22 INFO - PROCESS | 32048 | |2;jquery.html;357.2533405183167;375.2045663194029;390.482610541033;395.61625223533173;410.8787907567269;14425.63935901603;18627.254909803607;19277.325293614726;19832.504187395323;21133.471663208387;18865.528361790926;19522.511937201543;19899.403017909448;20071.799282007174;20104.497387565283;5566.332040155177;5814.24414826072;5820.097884827837;5820.796272130477;5825.543614096476;5410;5654.264945557072;5660.830175094733;5661.716914154275;5686.943130568703;5633.63381380211;5638.154276858483;5640.041192997201;5642.633228840113;5643.984082865082;5461.453854614527;5688.488036076752;5724.942750572524;5730.169125476783;5731.398203188689;5071.467495912912;5077.974610126963;5081.974590127063;5082.79668813247;5087.135187018179;13033.348332583333;14444.855551444456;21948.45128871775;22486.325410237718;22656.320310390714;2310.3992961829877;2343.203310874294;2377.7741114594087;2426.9393265168337;2879.956800647992;10139.695809125738;10227.693169204937;10273.38359698413;10637.521311540922;10707.089897358734;1804.855611551086;1887.2451019592272;1922.9039447514922;1924.8556358273186;1930;74437;86199;87364.02175651929;88155;89309;11169.609063682732;11352.375619341015;11618.128640352008;11749.4712737927;11806.881931180578;16111.758323625156;16143.67712645764;16146.111963842102;16169.434069807736;16196.271167797477;8207.630656620466;8366.414350995494;8417;8571.614277357534;8589.226969572768;7934.761957141295;8040.316573091236;8043.319301990047;8252.711155109542;8272.37957153216;4766.404199475066;4887.828925987574;4924.581410580141;4981.402231732143;5061.595072394273;1999.4801351648675;2003.3088584438347;2005.5888542848973;2045.3352660385435;2058.3516192399647;956.4033648643826;959.6593209410698;971.8979507151789;987.244757760314;1052.8157572424795;19550.70673939892;20189.192432302836;20235.898820505805;20303.796962030487;20361;982.3418309732497;1008.778068824872;1025.8050970315765;1027.2398425165472;1041.146260066738;10609.416482093404;11399.48702308398;11429.428528573704;11456.312621242752;11477.483513241757;457962;461103;474197;476367;476966;91980.54009729909;94097.529512352;96724.51637741907;98059;100796.49601751944;479.9136155491975;493.5410068636203;494.03909396224;494.85896519491774;496.5803895708121;10809.513571889442;11193.72015699614;11561;11714.531418743154;12239.32683702405;40684.79657601693;40813;40943.590564093975;42073.78963105165;42197.15605687931;54831.35505934829;58201.70899145477;58676.4132358688;59015.70492147512;60470.395296048235;8464.44981076228;8856.955715221382;8859.867101993605;8865.734027978913;9166.129217724305;9992.65025724096;13063.673408164492;13100.475980960655;13322.866771332163;13876.306184690928;11249;11349.262297950607;11509.712257193638;11689.473973671034;12141.939290303491;11464.885351146382;11713.765724685289;11726.182441721547;11852.288862668267;11935.732210879905;11943.343116128697;12001;12016.33910134951;12068.939655301667;12844.486220551073;8620.52587107715;8622.353323500887;8639.481631102153;8729.3889427742;9069.455832649799;9018.819623607622;10773.299735517166;11654.30074195551;11668.591599293984;11962.700932476757 19:28:22 INFO - PROCESS | 32048 | |3;mootools.html;1317.189928194161;1491.843356447573;1503.5113588083873;1612.9435469758562;1777.1469694546624;8550.871736923944;8955.328350373724;9054.728358149267;9230.67692630758;9238.353315267917;9005.099490050992;9105.135012173849;9112.635494580225;9118.954405227982;9129.315301352408;2102.190656597206;2296.712910886139;2407.0612461140167;2433.75662433757;2435.5859503884294;2400.975990240093;2434.014224239177;2436.6223235398543;2437.9756202437925;2439.4755127647554;2371.691680081587;2377.2274010946426;2391.940201494959;2426.514697060586;2431.3192306154215;2086.6035453263985;2156.8382371322214;2381.368937231626;2389.713234411873;2394.485185685086;2092.3513710749717;2282.6461898405773;2361.0437772702085;2361.6929799126174;2372.5373552157334;9275.907240927572;9343.112404321579;9357.251419886392;9359.906400935972;9367.063293670646;5227.059129356714;5354.27717258169;5358.678479291255;5365.517103460707;5366.463353664602;3311.3211791582908;3462.7576069675133;3477.078574177845;3482.5995010573856;3516.5780106387397;1011.5953618552564;1060.2260349944584;1065.4672663668166;1072.908802751767;1113.4877956140185;12733.42699578512;12767.489300427971;12775.808362874564;12784.616461506077;12810;4467.5532446755715;4648.395708557866;4788.1381351356395;4792.568668819823;4804.735739534359;10685.305455145392;10704.839427408595;10715.678529644123;10719.088877445349;10741.19441041925;5440.700761458158;5446.8910621787345;5448.727563621803;5454.7272636368025;5456.290682211287;4317.330813723878;4807.735574543433;4935.7532123393685;4977.352944117241;5002.499750024968;3866.96133038666;3974.602539746002;4050.736702114353;4052;4079.4288799568085;1264.3172686749053;1264.7217612125319;1271.5676669932081;1276.5340650662442;1279.8912092472058;996.6163027234567;1004.3471743366724;1013.2096964367805;1025.99487002566;1055.4933631856752;6057.303410107806;6103.6032657877095;6206.658633775095;6214.844628884223;6497.642629655415;1038.2524582300732;1040.0587468341164;1063.2663462211203;1102.2229328323513;1104.2049724198564;4296.334068219431;4403.053343531156;4502;4569.862904112882;4718.268668356411;11468.598599048993;11468.885311146782;11626.128040396836;11632.302061876315;11651.766964660828;11071.50178241965;11512.251703639236;11517.136214783757;11517.827232591519;11551;8331;8621.439606425562;8629.568521573896;8630.877985861798;8637.913620863963;6139.416755408229;6317.747290108344;6473.061406096184;6475.935240647722;6503.382178693017;10520.421376824042;10558.996895294935;10668.786624267626;10723.731906702395;10731.731706707395;10309;10669.839952400875;10736.892631073903;10811.243212975016;10837.620683276047;7161.4270858330165;7932.801679958047;8177.509349438815;8188.754337369888;8395.95802020986;9034.14175653312;9149.77125571866;9155;9160.725178244664;9161.633534658806;8015.078265999485;8024.358051355761;8166.959165204136;8231.218034286732;8356.958215208884;7769.6892124316655;7857.18183125912;7942.880856787268;7960.283574478325;8005.439619226599;7373.8156546086775;7424.331810136898;7460.142083660231;7470.738524151629;7500.399968002655;5216.452272511332;5271.604629652715;5309.177077552986;5309.36287645485;5456.809011684572;5452.563794896478;5582.888342233214;5601.523870470893;5609.354924183771;5669.291338582677 19:28:22 INFO - PROCESS | 32048 | |4;prototype.html;324.35939020434637;331.62857599488575;332.37347599774426;341.5150486309442;342.62824835053965;5221.86945326367;5262.158054711247;5273.551748101411;5278.947210527898;5305.442928492511;5175.197844334134;5177.844664660057;5257.684538927667;5258.1849813278905;5260.55285300749;3903.394973779069;3936.5079365079364;4043.919121617566;4077.674755704393;4109.650679692215;4075.6331930126285;4079.2657321682086;4096.733712308705;4125.711200215986;4138.937915931264;4052.3993022755994;4054.797260136996;4065.918681626366;4070.5115386153702;4080.1635664688815;3869.6517313441786;3988.880333589997;4071.857484988011;4079.551249362568;4117.629413352783;3588.4258518636893;3605.188832512664;3611.115276757211;3613.633070338932;3619.2761447710564;5032.370953630796;5041.521055499722;5042.873928151826;5052.949470505285;5081;4463.776811159461;4642.76786160694;4654.488006319302;4679.649026323005;4680.227762419197;5356.35723713153;5366;5366.785328586851;5369.919451208235;5396.487333703292;3969.7816620085882;3991.3015222335976;3992.40113982902;4012.558618551957;4022.0548171180044;5450.373207081197;5549.528290095347;5561.388247292835;5564.1931919871795;5658.886822263532;4073.796310184479;4076.307027805221;4077.6941729370415;4078.898027549276;4086.203190377848;4139.447707109834;4152.231837110145;4152.833886644561;4166.854160104382;4177.624013838769;4564.8402305919135;4605.700629459075;4718.504557021532;4727.267273572602;4731.243001119804;5371.838844834661;5391.353037635509;5402.028884113722;5437.510624043776;5441.863953401196;1410.3371415434729;1415.1296952374216;1428.9711407786376;1433.519770876768;1434.4477376210025;881.330189056309;910.658503061352;923.0861447167256;937.0020927712101;947.1570302430843;5153.561947234452;5275.762590683428;5281.392639846466;5284.894302113936;5289.513928453621;5570.387257401682;5577.972110139504;5582.330120385498;5584.91622625661;5602.1036634138345;5033.446320904697;5188.533032027136;5214.45248248936;5245.449227831099;5246.632735708464;2904.622399088105;2957.290250339946;2959.733623973853;2961.8596840216674;2963.940721185564;5788.363280039192;5803.955288048108;5819.563532735062;5827.213326200994;5841.182234487175;5577.972110139423;5617.157426386075;5679.716014199356;5681.886362272731;5716.256886604715;5690.487856092972;5746.224259724967;5750.654960702372;5776.740046697909;5785.65286082828;5302.867428314323;5498.697571633598;5544.473275038865;5556.861078473071;5574.721263936706;5496.945030549643;5498.147787093007;5498.395176530658;5513.200585914939;5514.711394771125;5769.8557536061935;5786.276715410574;5798.449147330997;5820.476157145878;5830.387809279964;3971.781552014667;4050.7974601270407;4064.3945641472046;4078.8164532596697;4080.428739976346;5496.340439147328;5504.80733174337;5504.834854954198;5507.834764957058;5522.944770552243;5303.628745987899;5363.651362661414;5388.245645609696;5402.297701298806;5424.915016996695;5221.582273418194;5300.6289559731995;5316.840494785162;5365.283109404953;5387.138057910715;5356.143017117242;5368.543673787615;5370.812021579226;5376.838694839161;5386.8383948481605;5244;5295.205719142159;5462.371827239759;5468.671879687232;5480.3971563128825;5340.813071542478;5351.438098999549;5354.089804733127;5371.301730774974;5375.596830237826 19:28:22 INFO - PROCESS | 32048 | |5;yui.html;671.9899201511977;681.8363592737744;695.6347917343396;712.2366208329211;768.8039549914771;14918.925405372971;15999.760003599926;16026.75959860603;16181.271842767075;16238.431654892067;14365.640858978531;15216.771748423782;15257.352941176483;15394.307256173472;15506.84493155071;819.7294892685429;840.7645859159445;842.4481964313385;843.1694780641063;843.3084870406269;669.5748199893054;803.1366281247682;839.3620848155415;845.750503601439;849.6558893648082;866.1511718515858;866.9479831210148;867.5185272173941;867.6746220167437;868.6655637579546;859.3125499600358;859.76786267708;861.3195575495382;861.4874149880842;862.211076864668;510.6170372220834;520.5185203686613;523.5079025715853;616.6084536319424;630.624778256941;14456.421743130262;14513.274336283144;14542.563723088324;14548.1998490083;14557.78163327551;13252.86747132526;13678.726425471534;13815.102018368874;13988.72022559543;13990.370433330525;12318.076144289214;12386.318752468611;12527.749445011048;12552.560660376934;12682.873171268262;4048.5344185418467;4084.223997440477;4108.281050816096;4246.447961764981;4299.419578356944;85149.57425212896;85711;86303.56848215782;86917.13082869153;88551;10529.578506901624;10864.402457864893;10927.45362731876;10930.234883558234;11495.827562586568;21673;21796.673049904264;22624.434389140402;22651.546969060855;22720.431989200402;7896;7990.840183196304;8042.678292868337;8049.3962952778775;8170;4171.97914010428;4239.173361194599;4311.374850646702;4337.696361254745;4346.6088052075465;3331.1006028372203;3423.4351332030433;3443.362977849106;3446.362422951762;3449.793012419263;612.2347066167291;616.4328817487922;629.9842503937347;630.6247782569363;632.2949910849462;517.5006119095091;567.0784974416573;617.5090802811775;654.2737561306946;663.5852592129919;8798.780030499289;9684;9808.117269445784;9828.361156524803;10012.39925604466;611.5291225756143;620.9658468784261;626.8401557602782;644.3781750610683;652.8444652964226;4361.760103194355;4412.889677758082;4537.886552836206;4596.5863072323655;4624.121416930772;107470.46264768626;125758.37120814338;129897;130181.34909325582;132240;108958;109106;111792;113978.43010784894;117380.41309793397;60362.69818650967;70895;93163;95051;95494;8515.361347898808;8584.785380365542;8603.483790972561;8615.053956029315;8827;55628.443715563946;56004.71997639986;56547.43452565586;56949;57621;75750;82073.58963205384;83902.58048709718;86066.13933860582;86595.56702216448;11802.704932376759;12050.397480125783;12162.817557736464;12565.246085234916;12673.683157920757;12719.491220351452;12818.294993775431;13560.660983475493;13634.318284085557;13999.860001399855;12020.278783273032;12447.93776031114;12848.35758212067;12852.357382130669;12918.677033074247;12105.33420661872;12133.211341263142;12368;12588.559400420942;12599;12093.213941093802;12188.939055304667;12395.256284622952;12413.131080824256;12912.870871291543;9970.252231082553;10043.598256069676;10165.491725413553;10266.743331416774;10671.46642667879;9291.073311906728;9697.32118751709;9702.126808587262;9870.309078364733;9878.259130565093 19:28:22 INFO - PROCESS | 32048 | __end_tp_report 19:28:22 INFO - PROCESS | 32048 | __start_cc_report 19:28:22 INFO - PROCESS | 32048 | _x_x_mozilla_cycle_collect,1036 19:28:22 INFO - PROCESS | 32048 | __end_cc_report 19:28:22 INFO - PROCESS | 32048 | __startTimestamp1471400902722__endTimestamp 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | ------- Summary: start ------- 19:28:22 INFO - PROCESS | 32048 | Number of tests: 6 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#0] dojo.html Cycles:165 Average:5318.00 Median:5514.09 stddev:609.48 (11.1%) stddev-sans-first:584.42 19:28:22 INFO - PROCESS | 32048 | Values: 3033.7 3052.9 3057.4 3073.0 3080.9 5166.9 5223.8 5245.7 5329.4 5417.3 5308.7 5318.7 5356.5 5359.6 5394.4 4770.2 4854.5 4877.3 4897.2 4954.8 4904.3 4928.4 4941.6 4962.9 4972.0 4782.1 4817.3 4822.7 4841.2 4940.4 4776.0 4777.6 4794.8 4960.6 4971.3 4791.6 4823.4 4839.6 4889.1 4890.9 5110.0 5171.4 5301.4 5308.1 5349.5 5563.1 5579.9 5663.3 5668.7 5681.3 5632.0 5713.7 5715.4 5716.3 5718.5 5158.0 5255.0 5275.7 5313.1 5329.9 5883.0 5884.0 5909.3 5937.8 5941.3 5638.2 5646.2 5665.6 5714.7 5716.3 5481.1 5571.1 5645.6 5782.3 5791.1 5692.7 5693.1 5693.7 5728.4 5747.8 5645.1 5685.0 5689.6 5691.6 5705.2 4235.6 4322.9 4375.5 4390.5 4432.2 3918.3 3934.6 3959.0 3975.9 3990.3 5455.1 5459.8 5464.9 5468.4 5517.4 5599.5 5664.0 5731.5 5735.5 5748.9 5468.9 5480.1 5506.7 5514.1 5548.1 4968.5 4992.7 4994.0 4998.5 5046.1 5687.7 5885.9 5978.3 5989.9 6000.7 5716.1 5811.4 5834.3 5867.7 5877.0 6001.3 6005.2 6017.6 6027.2 6029.9 5386.5 5551.1 5618.8 5654.1 5658.5 5712.1 5749.9 5778.4 5821.1 5838.1 5628.1 5636.1 5637.3 5641.7 5643.0 5389.9 5496.8 5589.3 5600.4 5600.8 5509.6 5596.9 5605.0 5638.3 5646.4 5662.0 5670.2 5672.7 5682.6 5686.6 5371.1 5380.4 5488.0 5497.3 5498.8 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#1] ext.html Cycles:180 Average:60912.71 Median:233052.79 stddev:120944.36 (51.9%) stddev-sans-first:121198.79 19:28:22 INFO - PROCESS | 32048 | Values: 574.9 595.6 602.8 618.4 619.3 41448.4 43009.8 43054.1 43081.4 46065.5 45062.8 45459.8 45575.5 45679.8 45715.0 8975.1 9123.0 9125.0 9135.5 9135.5 8962.5 9044.5 9055.5 9060.1 9062.3 9070.0 9101.5 9123.3 9127.3 9127.5 9058.1 9073.0 9090.0 9094.2 9103.0 8922.6 8993.9 8997.6 8998.5 9003.3 42577.4 42578.4 42631.8 42709.8 42974.8 3647.5 3715.5 3765.5 3773.8 4099.0 32153.5 32484.4 32512.0 32554.5 32640.2 3511.0 3524.5 3529.5 3535.7 3582.4 347531.0 347588.0 348578.0 348764.0 348781.0 56318.0 56400.0 56538.2 56698.0 56713.4 35790.5 36922.0 36976.8 38914.0 39069.0 16848.1 17046.4 17066.8 17079.6 17089.7 11188.2 11189.3 11190.6 11191.2 11278.8 19047.5 19107.3 19152.9 19153.8 19181.8 7168.0 7239.7 7378.8 7419.0 7460.8 964.5 1015.9 1063.3 1093.5 1174.5 32729.5 33356.3 33367.5 33458.2 34136.8 863.1 1148.4 1148.6 1154.5 1169.6 17359.5 17424.7 17450.7 17485.3 17500.4 555742.0 556692.0 558123.0 559504.0 559806.0 58352.1 58489.0 58645.7 58797.4 58872.7 1247.4 1363.1 1381.4 1393.2 1400.7 31905.7 33038.0 33866.8 33946.5 34388.5 50122.5 50516.0 50569.5 50599.5 50626.0 413060.0 421190.0 421307.0 423590.0 501723.0 43617.0 44497.0 44574.0 45193.0 45465.8 47485.0 48613.5 48739.0 48812.0 50170.2 41352.8 41939.2 42157.8 42228.4 43926.8 41928.8 41994.0 42014.0 43472.0 43794.8 41812.6 42515.6 42684.1 43473.0 43487.3 34342.0 37379.8 37953.0 38131.8 38451.6 28877.7 28953.2 29139.1 29200.0 29302.3 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#2] jquery.html Cycles:180 Average:28440.36 Median:382.84 stddev:77738.28 (20305.5%) stddev-sans-first:77927.76 19:28:22 INFO - PROCESS | 32048 | Values: 357.3 375.2 390.5 395.6 410.9 14425.6 18627.3 19277.3 19832.5 21133.5 18865.5 19522.5 19899.4 20071.8 20104.5 5566.3 5814.2 5820.1 5820.8 5825.5 5410.0 5654.3 5660.8 5661.7 5686.9 5633.6 5638.2 5640.0 5642.6 5644.0 5461.5 5688.5 5724.9 5730.2 5731.4 5071.5 5078.0 5082.0 5082.8 5087.1 13033.3 14444.9 21948.5 22486.3 22656.3 2310.4 2343.2 2377.8 2426.9 2880.0 10139.7 10227.7 10273.4 10637.5 10707.1 1804.9 1887.2 1922.9 1924.9 1930.0 74437.0 86199.0 87364.0 88155.0 89309.0 11169.6 11352.4 11618.1 11749.5 11806.9 16111.8 16143.7 16146.1 16169.4 16196.3 8207.6 8366.4 8417.0 8571.6 8589.2 7934.8 8040.3 8043.3 8252.7 8272.4 4766.4 4887.8 4924.6 4981.4 5061.6 1999.5 2003.3 2005.6 2045.3 2058.4 956.4 959.7 971.9 987.2 1052.8 19550.7 20189.2 20235.9 20303.8 20361.0 982.3 1008.8 1025.8 1027.2 1041.1 10609.4 11399.5 11429.4 11456.3 11477.5 457962.0 461103.0 474197.0 476367.0 476966.0 91980.5 94097.5 96724.5 98059.0 100796.5 479.9 493.5 494.0 494.9 496.6 10809.5 11193.7 11561.0 11714.5 12239.3 40684.8 40813.0 40943.6 42073.8 42197.2 54831.4 58201.7 58676.4 59015.7 60470.4 8464.4 8857.0 8859.9 8865.7 9166.1 9992.7 13063.7 13100.5 13322.9 13876.3 11249.0 11349.3 11509.7 11689.5 12141.9 11464.9 11713.8 11726.2 11852.3 11935.7 11943.3 12001.0 12016.3 12068.9 12844.5 8620.5 8622.4 8639.5 8729.4 9069.5 9018.8 10773.3 11654.3 11668.6 11962.7 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#3] mootools.html Cycles:180 Average:6052.10 Median:4360.19 stddev:3477.23 (79.7%) stddev-sans-first:3468.78 19:28:22 INFO - PROCESS | 32048 | Values: 1317.2 1491.8 1503.5 1612.9 1777.1 8550.9 8955.3 9054.7 9230.7 9238.4 9005.1 9105.1 9112.6 9119.0 9129.3 2102.2 2296.7 2407.1 2433.8 2435.6 2401.0 2434.0 2436.6 2438.0 2439.5 2371.7 2377.2 2391.9 2426.5 2431.3 2086.6 2156.8 2381.4 2389.7 2394.5 2092.4 2282.6 2361.0 2361.7 2372.5 9275.9 9343.1 9357.3 9359.9 9367.1 5227.1 5354.3 5358.7 5365.5 5366.5 3311.3 3462.8 3477.1 3482.6 3516.6 1011.6 1060.2 1065.5 1072.9 1113.5 12733.4 12767.5 12775.8 12784.6 12810.0 4467.6 4648.4 4788.1 4792.6 4804.7 10685.3 10704.8 10715.7 10719.1 10741.2 5440.7 5446.9 5448.7 5454.7 5456.3 4317.3 4807.7 4935.8 4977.4 5002.5 3867.0 3974.6 4050.7 4052.0 4079.4 1264.3 1264.7 1271.6 1276.5 1279.9 996.6 1004.3 1013.2 1026.0 1055.5 6057.3 6103.6 6206.7 6214.8 6497.6 1038.3 1040.1 1063.3 1102.2 1104.2 4296.3 4403.1 4502.0 4569.9 4718.3 11468.6 11468.9 11626.1 11632.3 11651.8 11071.5 11512.3 11517.1 11517.8 11551.0 8331.0 8621.4 8629.6 8630.9 8637.9 6139.4 6317.7 6473.1 6475.9 6503.4 10520.4 10559.0 10668.8 10723.7 10731.7 10309.0 10669.8 10736.9 10811.2 10837.6 7161.4 7932.8 8177.5 8188.8 8396.0 9034.1 9149.8 9155.0 9160.7 9161.6 8015.1 8024.4 8167.0 8231.2 8357.0 7769.7 7857.2 7942.9 7960.3 8005.4 7373.8 7424.3 7460.1 7470.7 7500.4 5216.5 5271.6 5309.2 5309.4 5456.8 5452.6 5582.9 5601.5 5609.4 5669.3 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#4] prototype.html Cycles:180 Average:4590.90 Median:5274.66 stddev:1332.99 (25.3%) stddev-sans-first:1297.69 19:28:22 INFO - PROCESS | 32048 | Values: 324.4 331.6 332.4 341.5 342.6 5221.9 5262.2 5273.6 5278.9 5305.4 5175.2 5177.8 5257.7 5258.2 5260.6 3903.4 3936.5 4043.9 4077.7 4109.7 4075.6 4079.3 4096.7 4125.7 4138.9 4052.4 4054.8 4065.9 4070.5 4080.2 3869.7 3988.9 4071.9 4079.6 4117.6 3588.4 3605.2 3611.1 3613.6 3619.3 5032.4 5041.5 5042.9 5052.9 5081.0 4463.8 4642.8 4654.5 4679.6 4680.2 5356.4 5366.0 5366.8 5369.9 5396.5 3969.8 3991.3 3992.4 4012.6 4022.1 5450.4 5549.5 5561.4 5564.2 5658.9 4073.8 4076.3 4077.7 4078.9 4086.2 4139.4 4152.2 4152.8 4166.9 4177.6 4564.8 4605.7 4718.5 4727.3 4731.2 5371.8 5391.4 5402.0 5437.5 5441.9 1410.3 1415.1 1429.0 1433.5 1434.4 881.3 910.7 923.1 937.0 947.2 5153.6 5275.8 5281.4 5284.9 5289.5 5570.4 5578.0 5582.3 5584.9 5602.1 5033.4 5188.5 5214.5 5245.4 5246.6 2904.6 2957.3 2959.7 2961.9 2963.9 5788.4 5804.0 5819.6 5827.2 5841.2 5578.0 5617.2 5679.7 5681.9 5716.3 5690.5 5746.2 5750.7 5776.7 5785.7 5302.9 5498.7 5544.5 5556.9 5574.7 5496.9 5498.1 5498.4 5513.2 5514.7 5769.9 5786.3 5798.4 5820.5 5830.4 3971.8 4050.8 4064.4 4078.8 4080.4 5496.3 5504.8 5504.8 5507.8 5522.9 5303.6 5363.7 5388.2 5402.3 5424.9 5221.6 5300.6 5316.8 5365.3 5387.1 5356.1 5368.5 5370.8 5376.8 5386.8 5244.0 5295.2 5462.4 5468.7 5480.4 5340.8 5351.4 5354.1 5371.3 5375.6 19:28:22 INFO - PROCESS | 32048 | 19:28:22 INFO - PROCESS | 32048 | [#5] yui.html Cycles:180 Average:21759.86 Median:4387.32 stddev:32972.36 (751.5%) stddev-sans-first:33026.84 19:28:22 INFO - PROCESS | 32048 | Values: 672.0 681.8 695.6 712.2 768.8 14918.9 15999.8 16026.8 16181.3 16238.4 14365.6 15216.8 15257.4 15394.3 15506.8 819.7 840.8 842.4 843.2 843.3 669.6 803.1 839.4 845.8 849.7 866.2 866.9 867.5 867.7 868.7 859.3 859.8 861.3 861.5 862.2 510.6 520.5 523.5 616.6 630.6 14456.4 14513.3 14542.6 14548.2 14557.8 13252.9 13678.7 13815.1 13988.7 13990.4 12318.1 12386.3 12527.7 12552.6 12682.9 4048.5 4084.2 4108.3 4246.4 4299.4 85149.6 85711.0 86303.6 86917.1 88551.0 10529.6 10864.4 10927.5 10930.2 11495.8 21673.0 21796.7 22624.4 22651.5 22720.4 7896.0 7990.8 8042.7 8049.4 8170.0 4172.0 4239.2 4311.4 4337.7 4346.6 3331.1 3423.4 3443.4 3446.4 3449.8 612.2 616.4 630.0 630.6 632.3 517.5 567.1 617.5 654.3 663.6 8798.8 9684.0 9808.1 9828.4 10012.4 611.5 621.0 626.8 644.4 652.8 4361.8 4412.9 4537.9 4596.6 4624.1 107470.5 125758.4 129897.0 130181.3 132240.0 108958.0 109106.0 111792.0 113978.4 117380.4 60362.7 70895.0 93163.0 95051.0 95494.0 8515.4 8584.8 8603.5 8615.1 8827.0 55628.4 56004.7 56547.4 56949.0 57621.0 75750.0 82073.6 83902.6 86066.1 86595.6 11802.7 12050.4 12162.8 12565.2 12673.7 12719.5 12818.3 13560.7 13634.3 13999.9 12020.3 12447.9 12848.4 12852.4 12918.7 12105.3 12133.2 12368.0 12588.6 12599.0 12093.2 12188.9 12395.3 12413.1 12912.9 9970.3 10043.6 10165.5 10266.7 10671.5 9291.1 9697.3 9702.1 9870.3 9878.3 19:28:22 INFO - PROCESS | 32048 | -------- Summary: end -------- 19:28:22 INFO - PROCESS | 32048 | 19:28:23 INFO - TEST-INFO | 32048: exit 0 19:28:23 INFO - TEST-OK | dromaeo_css | took 1114877ms 19:28:23 INFO - TEST-START | kraken 19:28:23 INFO - operating with platform_type : linux_ 19:28:23 INFO - Initialising browser for kraken test... 19:28:23 INFO - TEST-INFO | started process 32353 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTD6RO_/profile http://localhost:40786/getInfo.html) 19:28:27 INFO - PROCESS | 32353 | __metrics Screen width/height:1600/1200 19:28:27 INFO - PROCESS | 32353 | colorDepth:24 19:28:27 INFO - PROCESS | 32353 | Browser inner width/height: 1024/697 19:28:27 INFO - PROCESS | 32353 | __metrics 19:28:28 INFO - PROCESS | 32353 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:28:28 INFO - PROCESS | 32353 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:28:28 INFO - TEST-INFO | 32353: exit 0 19:28:28 INFO - Browser initialized. 19:28:28 INFO - Running cycle 1/1 for kraken test... 19:28:28 INFO - TEST-INFO | started process 32436 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTD6RO_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:40 INFO - PROCESS | 32436 | RSS: Main: 155914240 19:28:40 INFO - PROCESS | 32436 | 19:28:43 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40786/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:44 INFO - PROCESS | 32436 | RSS: Main: 203657216 19:28:44 INFO - PROCESS | 32436 | 19:28:46 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40786/tests/kraken/driver.html?testName=audio-dft) 19:28:47 INFO - PROCESS | 32436 | RSS: Main: 195538944 19:28:47 INFO - PROCESS | 32436 | 19:28:50 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40786/tests/kraken/driver.html?testName=audio-fft) 19:28:51 INFO - PROCESS | 32436 | RSS: Main: 276148224 19:28:51 INFO - PROCESS | 32436 | 19:28:53 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40786/tests/kraken/driver.html?testName=audio-oscillator) 19:28:53 INFO - PROCESS | 32436 | RSS: Main: 186490880 19:28:53 INFO - PROCESS | 32436 | 19:28:55 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40786/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:56 INFO - PROCESS | 32436 | RSS: Main: 191238144 19:28:56 INFO - PROCESS | 32436 | 19:28:59 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40786/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:00 INFO - PROCESS | 32436 | RSS: Main: 226340864 19:29:00 INFO - PROCESS | 32436 | 19:29:03 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40786/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:04 INFO - PROCESS | 32436 | RSS: Main: 227360768 19:29:04 INFO - PROCESS | 32436 | 19:29:06 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40786/tests/kraken/driver.html?testName=json-parse-financial) 19:29:07 INFO - PROCESS | 32436 | RSS: Main: 226369536 19:29:07 INFO - PROCESS | 32436 | 19:29:08 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40786/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:09 INFO - PROCESS | 32436 | RSS: Main: 188575744 19:29:09 INFO - PROCESS | 32436 | 19:29:11 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:11 INFO - PROCESS | 32436 | RSS: Main: 196149248 19:29:11 INFO - PROCESS | 32436 | 19:29:14 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:14 INFO - PROCESS | 32436 | RSS: Main: 192880640 19:29:14 INFO - PROCESS | 32436 | 19:29:17 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:17 INFO - PROCESS | 32436 | RSS: Main: 186810368 19:29:17 INFO - PROCESS | 32436 | 19:29:20 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:21 INFO - PROCESS | 32436 | RSS: Main: 191971328 19:29:21 INFO - PROCESS | 32436 | 19:29:22 INFO - PROCESS | 32436 | Cycle 1(1): loaded http://localhost:40786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40786/tests/kraken/driver.html?testName=ai-astar) 19:29:23 INFO - PROCESS | 32436 | RSS: Main: 242765824 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | __start_tp_report 19:29:23 INFO - PROCESS | 32436 | _x_x_mozilla_page_load 19:29:23 INFO - PROCESS | 32436 | _x_x_mozilla_page_load_details 19:29:23 INFO - PROCESS | 32436 | |i|pagename|runs| 19:29:23 INFO - PROCESS | 32436 | |0;ai-astar;101;111;111;111;107;111;112;112;113;110 19:29:23 INFO - PROCESS | 32436 | |1;audio-beat-detection;144;118;119;187;122;122;150;117;162;130 19:29:23 INFO - PROCESS | 32436 | |2;audio-dft;160;159;160;160;159;159;159;162;161;160 19:29:23 INFO - PROCESS | 32436 | |3;audio-fft;74;74;75;114;76;77;74;75;103;74 19:29:23 INFO - PROCESS | 32436 | |4;audio-oscillator;84;85;129;85;85;85;123;84;85;129 19:29:23 INFO - PROCESS | 32436 | |5;imaging-gaussian-blur;117;117;118;117;117;116;116;116;116;116 19:29:23 INFO - PROCESS | 32436 | |6;imaging-darkroom;146;147;145;146;146;147;149;146;145;146 19:29:23 INFO - PROCESS | 32436 | |7;imaging-desaturate;96;96;95;96;95;95;95;95;96;95 19:29:23 INFO - PROCESS | 32436 | |8;json-parse-financial;54;49;51;49;51;51;51;49;51;50 19:29:23 INFO - PROCESS | 32436 | |9;json-stringify-tinderbox;61;62;61;62;62;60;62;62;62;62 19:29:23 INFO - PROCESS | 32436 | |10;stanford-crypto-aes;82;80;83;78;78;82;77;78;80;80 19:29:23 INFO - PROCESS | 32436 | |11;stanford-crypto-ccm;126;123;119;122;120;124;124;119;161;124 19:29:23 INFO - PROCESS | 32436 | |12;stanford-crypto-pbkdf2;165;165;164;162;163;167;166;163;164;165 19:29:23 INFO - PROCESS | 32436 | |13;stanford-crypto-sha256-iterative;64;65;66;65;64;64;63;63;66;63 19:29:23 INFO - PROCESS | 32436 | __end_tp_report 19:29:23 INFO - PROCESS | 32436 | __start_cc_report 19:29:23 INFO - PROCESS | 32436 | _x_x_mozilla_cycle_collect,1676 19:29:23 INFO - PROCESS | 32436 | __end_cc_report 19:29:23 INFO - PROCESS | 32436 | __startTimestamp1471400963129__endTimestamp 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | ------- Summary: start ------- 19:29:23 INFO - PROCESS | 32436 | Number of tests: 14 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.51 (3.2%) stddev-sans-first:1.69 19:29:23 INFO - PROCESS | 32436 | Values: 101.0 111.0 111.0 111.0 107.0 111.0 112.0 112.0 113.0 110.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:137.00 stddev:23.40 (17.1%) stddev-sans-first:24.68 19:29:23 INFO - PROCESS | 32436 | Values: 144.0 118.0 119.0 187.0 122.0 122.0 150.0 117.0 162.0 130.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 19:29:23 INFO - PROCESS | 32436 | Values: 160.0 159.0 160.0 160.0 159.0 159.0 159.0 162.0 161.0 160.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#3] audio-fft Cycles:10 Average:81.60 Median:74.50 stddev:14.45 (19.4%) stddev-sans-first:15.06 19:29:23 INFO - PROCESS | 32436 | Values: 74.0 74.0 75.0 114.0 76.0 77.0 74.0 75.0 103.0 74.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:85.00 stddev:20.49 (24.1%) stddev-sans-first:21.16 19:29:23 INFO - PROCESS | 32436 | Values: 84.0 85.0 129.0 85.0 85.0 85.0 123.0 84.0 85.0 129.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 19:29:23 INFO - PROCESS | 32436 | Values: 117.0 117.0 118.0 117.0 117.0 116.0 116.0 116.0 116.0 116.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.16 (0.8%) stddev-sans-first:1.22 19:29:23 INFO - PROCESS | 32436 | Values: 146.0 147.0 145.0 146.0 146.0 147.0 149.0 146.0 145.0 146.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 19:29:23 INFO - PROCESS | 32436 | Values: 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.51 (3.0%) stddev-sans-first:0.97 19:29:23 INFO - PROCESS | 32436 | Values: 54.0 49.0 51.0 49.0 51.0 51.0 51.0 49.0 51.0 50.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 19:29:23 INFO - PROCESS | 32436 | Values: 61.0 62.0 61.0 62.0 62.0 60.0 62.0 62.0 62.0 62.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:2.04 (2.6%) stddev-sans-first:2.01 19:29:23 INFO - PROCESS | 32436 | Values: 82.0 80.0 83.0 78.0 78.0 82.0 77.0 78.0 80.0 80.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:124.00 stddev:12.45 (10.0%) stddev-sans-first:13.21 19:29:23 INFO - PROCESS | 32436 | Values: 126.0 123.0 119.0 122.0 120.0 124.0 124.0 119.0 161.0 124.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:165.00 stddev:1.51 (0.9%) stddev-sans-first:1.58 19:29:23 INFO - PROCESS | 32436 | Values: 165.0 165.0 164.0 162.0 163.0 167.0 166.0 163.0 164.0 165.0 19:29:23 INFO - PROCESS | 32436 | 19:29:23 INFO - PROCESS | 32436 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.50 stddev:1.16 (1.8%) stddev-sans-first:1.22 19:29:23 INFO - PROCESS | 32436 | Values: 64.0 65.0 66.0 65.0 64.0 64.0 63.0 63.0 66.0 63.0 19:29:23 INFO - PROCESS | 32436 | -------- Summary: end -------- 19:29:23 INFO - PROCESS | 32436 | 19:29:24 INFO - TEST-INFO | 32436: exit 0 19:29:24 INFO - TEST-OK | kraken | took 60667ms 19:29:24 INFO - SUITE-END | took 1175s 19:29:24 INFO - Completed test suite (00:19:35) 19:29:24 INFO - javascript benchmark 19:29:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.742131918787, 3052.938941221177, 3057.419090372829, 3073.047355319851, 3080.9229769255767, 5166.9224961625605, 5223.764930578123, 5245.685258884465, 5329.3650793650795, 5417.2686687297255, 5308.654937429064, 5318.680879147244, 5356.464353564642, 5359.5712343012565, 5394.406615272317, 4770.236762118062, 4854.538818812225, 4877.317175595402, 4897.191963326047, 4954.7522623868845, 4904.313396124546, 4928.408590969088, 4941.579965702919, 4962.851114466572, 4972.0, 4782.091402633523, 4817.325574419584, 4822.710637361734, 4841.2012018016985, 4940.382452193476, 4776.020915712305, 4777.617790576745, 4794.832180873687, 4960.553550180465, 4971.254311853215, 4791.592714619262, 4823.445303790074, 4839.564439200453, 4889.119958407486, 4890.853274401774, 5110.003549307887, 5171.405288391846, 5301.363836339664, 5308.124159513675, 5349.465053494659, 5563.13771365439, 5579.888402231933, 5663.348714897757, 5668.744906479177, 5681.318241811009, 5631.9718401408145, 5713.657180569179, 5715.3998830122655, 5716.314042314907, 5718.513926316226, 5157.999999999963, 5254.973725131388, 5275.709835959059, 5313.149896016657, 5329.920051199235, 5883.0, 5884.0, 5909.349971503131, 5937.762489500372, 5941.346451890288, 5638.238837756932, 5646.153077038477, 5665.631733937281, 5714.657120572779, 5716.342620598602, 5481.123020316731, 5571.0807716727095, 5645.604807663424, 5782.335031471434, 5791.0734282514595, 5692.658440493584, 5693.117566777156, 5693.743781529841, 5728.398518155617, 5747.798827041033, 5645.068563686946, 5685.0, 5689.630174038674, 5691.573132015115, 5705.1727499512335, 4235.639970602471, 4322.935155972663, 4375.47494300686, 4390.4511936008, 4432.180046691373, 3918.314294998364, 3934.6458818706456, 3958.980205098956, 3975.860844870416, 3990.2817492850986, 5455.072637651609, 5459.754311056012, 5464.9180262296095, 5468.425815289417, 5517.4482551745305, 5599.468050535193, 5663.9716801415725, 5731.512821410143, 5735.541156707453, 5748.913766293509, 5468.863278418072, 5480.095784195642, 5506.669599824023, 5514.09017512114, 5548.0845660465575, 4968.453470118284, 4992.725400102957, 4994.0, 4998.475160108209, 5046.066477701637, 5687.744051517609, 5885.941140588539, 5978.252718410199, 5989.9101513477335, 6000.699965001732, 5716.142578613241, 5811.389804070512, 5834.329052159056, 5867.706614669164, 5877.0302900021015, 6001.309849367204, 6005.249343832021, 6017.608855424383, 6027.21646185993, 6029.909551356821, 5386.48828361305, 5551.111822108443, 5618.775248990158, 5654.123610840326, 5658.519025882846, 5712.143178523254, 5749.913751293818, 5778.422157784188, 5821.068629019336, 5838.095095260242, 5628.127640215773, 5636.070048441961, 5637.3235211774845, 5641.689707066151, 5643.040683083804, 5389.919151212814, 5496.835094947158, 5589.329280486368, 5600.35595906476, 5600.831975040755, 5509.586780991362, 5596.916046259228, 5604.971975140098, 5638.323401191883, 5646.3788983212635, 5662.0, 5670.206171136127, 5672.688002159921, 5682.6306290091015, 5686.60193786431, 5371.0869152244995, 5380.354357476971, 5487.972560137174, 5497.257870187634, 5498.80754173602], "value": 5276.462231172132, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.8993926062941, 595.6455908734303, 602.8214839987825, 618.3569088148327, 619.334215718103, 41448.37827432587, 43009.78495107528, 43054.13891722167, 43081.35377969329, 46065.53934460654, 45062.774686126606, 45459.77270113645, 45575.544244557546, 45679.771601142034, 45715.0, 8975.14736100071, 9123.0, 9125.04187060359, 9135.451872887616, 9135.543222838865, 8962.462252264851, 9044.502552359618, 9055.547222638874, 9060.093990600953, 9062.275017998545, 9069.954650226773, 9101.453912765222, 9123.315751318645, 9127.269818414545, 9127.497987610679, 9058.139476749731, 9072.95463522678, 9089.954550227272, 9094.226990705798, 9103.0, 8922.553872306358, 8993.910060899438, 8997.59510822008, 8998.505082220476, 9003.279737620975, 42577.36133957993, 42578.36132458015, 42631.78684106591, 42709.786451067856, 42974.785126074166, 3647.5258216431694, 3715.4612581175847, 3765.4540091686554, 3773.754705944132, 4098.979505102456, 32153.51769723456, 32484.350312993607, 32512.0, 32554.511682324784, 32640.183995400068, 3511.0, 3524.4713293006, 3529.4882242075014, 3535.681788639009, 3582.4268117101137, 347531.0, 347588.0, 348578.0, 348764.0, 348781.0, 56318.0, 56400.0, 56538.15192772112, 56698.0, 56713.43286567164, 35790.463143052875, 36922.0, 36976.81511592425, 38914.0, 39069.0, 16848.07335596554, 17046.403375881786, 17066.829331706525, 17079.573010674834, 17089.658206835793, 11188.160887933438, 11189.272697274651, 11190.608328708619, 11191.216614836883, 11278.774424511628, 19047.523811904535, 19107.331243406414, 19152.904235478734, 19153.80846191548, 19181.80818191828, 7168.0, 7239.674214660353, 7378.778636640909, 7419.0, 7460.813479662943, 964.5032808103832, 1015.8679371681634, 1063.3194755356578, 1093.4751319366749, 1174.4597485156924, 32729.509057364157, 33356.3328733424, 33367.49948750771, 33458.16354591155, 34136.82931585326, 863.0721973878107, 1148.3798748675788, 1148.5635458525874, 1154.543955137716, 1169.561414469574, 17359.479215623553, 17424.738628920575, 17450.65098698019, 17485.300587976337, 17500.387486437914, 555742.0, 556692.0, 558123.0, 559504.0, 559806.0, 58352.124718129264, 58489.0, 58645.70677146587, 58797.412025879195, 58872.705636471546, 1247.3700781105483, 1363.1003537665094, 1381.3990913952464, 1393.226759148662, 1400.7268582626493, 31905.68094319027, 33038.0, 33866.8306658475, 33946.49080263748, 34388.48417273793, 50122.49877501178, 50516.0, 50569.494305056476, 50599.49400505948, 50626.0, 413060.0, 421190.0, 421307.0, 423590.0, 501723.0, 43617.0, 44497.0, 44574.0, 45193.0, 45465.77267113643, 47485.0, 48613.51386486231, 48739.0, 48812.0, 50170.24744628907, 41352.79323603483, 41939.16121677488, 42157.78921105375, 42228.36657450079, 43926.78036609796, 41928.79035604924, 41994.0, 42014.0, 43472.0, 43794.781026094664, 41812.58187418087, 42515.57484425116, 42684.146317074104, 43473.0, 43487.347689784045, 34342.0, 37379.81310093432, 37953.0, 38131.80934095423, 38451.615483845926, 28877.7112228875, 28953.236079309554, 29139.12582622525, 29200.0, 29302.26744331409], "value": 20256.38329007656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.2533405183167, 375.2045663194029, 390.482610541033, 395.61625223533173, 410.8787907567269, 14425.63935901603, 18627.254909803607, 19277.325293614726, 19832.504187395323, 21133.471663208387, 18865.528361790926, 19522.511937201543, 19899.403017909448, 20071.799282007174, 20104.497387565283, 5566.332040155177, 5814.24414826072, 5820.097884827837, 5820.796272130477, 5825.543614096476, 5410.0, 5654.264945557072, 5660.830175094733, 5661.716914154275, 5686.943130568703, 5633.63381380211, 5638.154276858483, 5640.041192997201, 5642.633228840113, 5643.984082865082, 5461.453854614527, 5688.488036076752, 5724.942750572524, 5730.169125476783, 5731.398203188689, 5071.467495912912, 5077.974610126963, 5081.974590127063, 5082.79668813247, 5087.135187018179, 13033.348332583333, 14444.855551444456, 21948.45128871775, 22486.325410237718, 22656.320310390714, 2310.3992961829877, 2343.203310874294, 2377.7741114594087, 2426.9393265168337, 2879.956800647992, 10139.695809125738, 10227.693169204937, 10273.38359698413, 10637.521311540922, 10707.089897358734, 1804.855611551086, 1887.2451019592272, 1922.9039447514922, 1924.8556358273186, 1930.0, 74437.0, 86199.0, 87364.02175651929, 88155.0, 89309.0, 11169.609063682732, 11352.375619341015, 11618.128640352008, 11749.4712737927, 11806.881931180578, 16111.758323625156, 16143.67712645764, 16146.111963842102, 16169.434069807736, 16196.271167797477, 8207.630656620466, 8366.414350995494, 8417.0, 8571.614277357534, 8589.226969572768, 7934.761957141295, 8040.316573091236, 8043.319301990047, 8252.711155109542, 8272.37957153216, 4766.404199475066, 4887.828925987574, 4924.581410580141, 4981.402231732143, 5061.595072394273, 1999.4801351648675, 2003.3088584438347, 2005.5888542848973, 2045.3352660385435, 2058.3516192399647, 956.4033648643826, 959.6593209410698, 971.8979507151789, 987.244757760314, 1052.8157572424795, 19550.70673939892, 20189.192432302836, 20235.898820505805, 20303.796962030487, 20361.0, 982.3418309732497, 1008.778068824872, 1025.8050970315765, 1027.2398425165472, 1041.146260066738, 10609.416482093404, 11399.48702308398, 11429.428528573704, 11456.312621242752, 11477.483513241757, 457962.0, 461103.0, 474197.0, 476367.0, 476966.0, 91980.54009729909, 94097.529512352, 96724.51637741907, 98059.0, 100796.49601751944, 479.9136155491975, 493.5410068636203, 494.03909396224, 494.85896519491774, 496.5803895708121, 10809.513571889442, 11193.72015699614, 11561.0, 11714.531418743154, 12239.32683702405, 40684.79657601693, 40813.0, 40943.590564093975, 42073.78963105165, 42197.15605687931, 54831.35505934829, 58201.70899145477, 58676.4132358688, 59015.70492147512, 60470.395296048235, 8464.44981076228, 8856.955715221382, 8859.867101993605, 8865.734027978913, 9166.129217724305, 9992.65025724096, 13063.673408164492, 13100.475980960655, 13322.866771332163, 13876.306184690928, 11249.0, 11349.262297950607, 11509.712257193638, 11689.473973671034, 12141.939290303491, 11464.885351146382, 11713.765724685289, 11726.182441721547, 11852.288862668267, 11935.732210879905, 11943.343116128697, 12001.0, 12016.33910134951, 12068.939655301667, 12844.486220551073, 8620.52587107715, 8622.353323500887, 8639.481631102153, 8729.3889427742, 9069.455832649799, 9018.819623607622, 10773.299735517166, 11654.30074195551, 11668.591599293984, 11962.700932476757], "value": 8952.775899392229, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.189928194161, 1491.843356447573, 1503.5113588083873, 1612.9435469758562, 1777.1469694546624, 8550.871736923944, 8955.328350373724, 9054.728358149267, 9230.67692630758, 9238.353315267917, 9005.099490050992, 9105.135012173849, 9112.635494580225, 9118.954405227982, 9129.315301352408, 2102.190656597206, 2296.712910886139, 2407.0612461140167, 2433.75662433757, 2435.5859503884294, 2400.975990240093, 2434.014224239177, 2436.6223235398543, 2437.9756202437925, 2439.4755127647554, 2371.691680081587, 2377.2274010946426, 2391.940201494959, 2426.514697060586, 2431.3192306154215, 2086.6035453263985, 2156.8382371322214, 2381.368937231626, 2389.713234411873, 2394.485185685086, 2092.3513710749717, 2282.6461898405773, 2361.0437772702085, 2361.6929799126174, 2372.5373552157334, 9275.907240927572, 9343.112404321579, 9357.251419886392, 9359.906400935972, 9367.063293670646, 5227.059129356714, 5354.27717258169, 5358.678479291255, 5365.517103460707, 5366.463353664602, 3311.3211791582908, 3462.7576069675133, 3477.078574177845, 3482.5995010573856, 3516.5780106387397, 1011.5953618552564, 1060.2260349944584, 1065.4672663668166, 1072.908802751767, 1113.4877956140185, 12733.42699578512, 12767.489300427971, 12775.808362874564, 12784.616461506077, 12810.0, 4467.5532446755715, 4648.395708557866, 4788.1381351356395, 4792.568668819823, 4804.735739534359, 10685.305455145392, 10704.839427408595, 10715.678529644123, 10719.088877445349, 10741.19441041925, 5440.700761458158, 5446.8910621787345, 5448.727563621803, 5454.7272636368025, 5456.290682211287, 4317.330813723878, 4807.735574543433, 4935.7532123393685, 4977.352944117241, 5002.499750024968, 3866.96133038666, 3974.602539746002, 4050.736702114353, 4052.0, 4079.4288799568085, 1264.3172686749053, 1264.7217612125319, 1271.5676669932081, 1276.5340650662442, 1279.8912092472058, 996.6163027234567, 1004.3471743366724, 1013.2096964367805, 1025.99487002566, 1055.4933631856752, 6057.303410107806, 6103.6032657877095, 6206.658633775095, 6214.844628884223, 6497.642629655415, 1038.2524582300732, 1040.0587468341164, 1063.2663462211203, 1102.2229328323513, 1104.2049724198564, 4296.334068219431, 4403.053343531156, 4502.0, 4569.862904112882, 4718.268668356411, 11468.598599048993, 11468.885311146782, 11626.128040396836, 11632.302061876315, 11651.766964660828, 11071.50178241965, 11512.251703639236, 11517.136214783757, 11517.827232591519, 11551.0, 8331.0, 8621.439606425562, 8629.568521573896, 8630.877985861798, 8637.913620863963, 6139.416755408229, 6317.747290108344, 6473.061406096184, 6475.935240647722, 6503.382178693017, 10520.421376824042, 10558.996895294935, 10668.786624267626, 10723.731906702395, 10731.731706707395, 10309.0, 10669.839952400875, 10736.892631073903, 10811.243212975016, 10837.620683276047, 7161.4270858330165, 7932.801679958047, 8177.509349438815, 8188.754337369888, 8395.95802020986, 9034.14175653312, 9149.77125571866, 9155.0, 9160.725178244664, 9161.633534658806, 8015.078265999485, 8024.358051355761, 8166.959165204136, 8231.218034286732, 8356.958215208884, 7769.6892124316655, 7857.18183125912, 7942.880856787268, 7960.283574478325, 8005.439619226599, 7373.8156546086775, 7424.331810136898, 7460.142083660231, 7470.738524151629, 7500.399968002655, 5216.452272511332, 5271.604629652715, 5309.177077552986, 5309.36287645485, 5456.809011684572, 5452.563794896478, 5582.888342233214, 5601.523870470893, 5609.354924183771, 5669.291338582677], "value": 4812.6192932895665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.35939020434637, 331.62857599488575, 332.37347599774426, 341.5150486309442, 342.62824835053965, 5221.86945326367, 5262.158054711247, 5273.551748101411, 5278.947210527898, 5305.442928492511, 5175.197844334134, 5177.844664660057, 5257.684538927667, 5258.1849813278905, 5260.55285300749, 3903.394973779069, 3936.5079365079364, 4043.919121617566, 4077.674755704393, 4109.650679692215, 4075.6331930126285, 4079.2657321682086, 4096.733712308705, 4125.711200215986, 4138.937915931264, 4052.3993022755994, 4054.797260136996, 4065.918681626366, 4070.5115386153702, 4080.1635664688815, 3869.6517313441786, 3988.880333589997, 4071.857484988011, 4079.551249362568, 4117.629413352783, 3588.4258518636893, 3605.188832512664, 3611.115276757211, 3613.633070338932, 3619.2761447710564, 5032.370953630796, 5041.521055499722, 5042.873928151826, 5052.949470505285, 5081.0, 4463.776811159461, 4642.76786160694, 4654.488006319302, 4679.649026323005, 4680.227762419197, 5356.35723713153, 5366.0, 5366.785328586851, 5369.919451208235, 5396.487333703292, 3969.7816620085882, 3991.3015222335976, 3992.40113982902, 4012.558618551957, 4022.0548171180044, 5450.373207081197, 5549.528290095347, 5561.388247292835, 5564.1931919871795, 5658.886822263532, 4073.796310184479, 4076.307027805221, 4077.6941729370415, 4078.898027549276, 4086.203190377848, 4139.447707109834, 4152.231837110145, 4152.833886644561, 4166.854160104382, 4177.624013838769, 4564.8402305919135, 4605.700629459075, 4718.504557021532, 4727.267273572602, 4731.243001119804, 5371.838844834661, 5391.353037635509, 5402.028884113722, 5437.510624043776, 5441.863953401196, 1410.3371415434729, 1415.1296952374216, 1428.9711407786376, 1433.519770876768, 1434.4477376210025, 881.330189056309, 910.658503061352, 923.0861447167256, 937.0020927712101, 947.1570302430843, 5153.561947234452, 5275.762590683428, 5281.392639846466, 5284.894302113936, 5289.513928453621, 5570.387257401682, 5577.972110139504, 5582.330120385498, 5584.91622625661, 5602.1036634138345, 5033.446320904697, 5188.533032027136, 5214.45248248936, 5245.449227831099, 5246.632735708464, 2904.622399088105, 2957.290250339946, 2959.733623973853, 2961.8596840216674, 2963.940721185564, 5788.363280039192, 5803.955288048108, 5819.563532735062, 5827.213326200994, 5841.182234487175, 5577.972110139423, 5617.157426386075, 5679.716014199356, 5681.886362272731, 5716.256886604715, 5690.487856092972, 5746.224259724967, 5750.654960702372, 5776.740046697909, 5785.65286082828, 5302.867428314323, 5498.697571633598, 5544.473275038865, 5556.861078473071, 5574.721263936706, 5496.945030549643, 5498.147787093007, 5498.395176530658, 5513.200585914939, 5514.711394771125, 5769.8557536061935, 5786.276715410574, 5798.449147330997, 5820.476157145878, 5830.387809279964, 3971.781552014667, 4050.7974601270407, 4064.3945641472046, 4078.8164532596697, 4080.428739976346, 5496.340439147328, 5504.80733174337, 5504.834854954198, 5507.834764957058, 5522.944770552243, 5303.628745987899, 5363.651362661414, 5388.245645609696, 5402.297701298806, 5424.915016996695, 5221.582273418194, 5300.6289559731995, 5316.840494785162, 5365.283109404953, 5387.138057910715, 5356.143017117242, 5368.543673787615, 5370.812021579226, 5376.838694839161, 5386.8383948481605, 5244.0, 5295.205719142159, 5462.371827239759, 5468.671879687232, 5480.3971563128825, 5340.813071542478, 5351.438098999549, 5354.089804733127, 5371.301730774974, 5375.596830237826], "value": 4167.625281521249, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.9899201511977, 681.8363592737744, 695.6347917343396, 712.2366208329211, 768.8039549914771, 14918.925405372971, 15999.760003599926, 16026.75959860603, 16181.271842767075, 16238.431654892067, 14365.640858978531, 15216.771748423782, 15257.352941176483, 15394.307256173472, 15506.84493155071, 819.7294892685429, 840.7645859159445, 842.4481964313385, 843.1694780641063, 843.3084870406269, 669.5748199893054, 803.1366281247682, 839.3620848155415, 845.750503601439, 849.6558893648082, 866.1511718515858, 866.9479831210148, 867.5185272173941, 867.6746220167437, 868.6655637579546, 859.3125499600358, 859.76786267708, 861.3195575495382, 861.4874149880842, 862.211076864668, 510.6170372220834, 520.5185203686613, 523.5079025715853, 616.6084536319424, 630.624778256941, 14456.421743130262, 14513.274336283144, 14542.563723088324, 14548.1998490083, 14557.78163327551, 13252.86747132526, 13678.726425471534, 13815.102018368874, 13988.72022559543, 13990.370433330525, 12318.076144289214, 12386.318752468611, 12527.749445011048, 12552.560660376934, 12682.873171268262, 4048.5344185418467, 4084.223997440477, 4108.281050816096, 4246.447961764981, 4299.419578356944, 85149.57425212896, 85711.0, 86303.56848215782, 86917.13082869153, 88551.0, 10529.578506901624, 10864.402457864893, 10927.45362731876, 10930.234883558234, 11495.827562586568, 21673.0, 21796.673049904264, 22624.434389140402, 22651.546969060855, 22720.431989200402, 7896.0, 7990.840183196304, 8042.678292868337, 8049.3962952778775, 8170.0, 4171.97914010428, 4239.173361194599, 4311.374850646702, 4337.696361254745, 4346.6088052075465, 3331.1006028372203, 3423.4351332030433, 3443.362977849106, 3446.362422951762, 3449.793012419263, 612.2347066167291, 616.4328817487922, 629.9842503937347, 630.6247782569363, 632.2949910849462, 517.5006119095091, 567.0784974416573, 617.5090802811775, 654.2737561306946, 663.5852592129919, 8798.780030499289, 9684.0, 9808.117269445784, 9828.361156524803, 10012.39925604466, 611.5291225756143, 620.9658468784261, 626.8401557602782, 644.3781750610683, 652.8444652964226, 4361.760103194355, 4412.889677758082, 4537.886552836206, 4596.5863072323655, 4624.121416930772, 107470.46264768626, 125758.37120814338, 129897.0, 130181.34909325582, 132240.0, 108958.0, 109106.0, 111792.0, 113978.43010784894, 117380.41309793397, 60362.69818650967, 70895.0, 93163.0, 95051.0, 95494.0, 8515.361347898808, 8584.785380365542, 8603.483790972561, 8615.053956029315, 8827.0, 55628.443715563946, 56004.71997639986, 56547.43452565586, 56949.0, 57621.0, 75750.0, 82073.58963205384, 83902.58048709718, 86066.13933860582, 86595.56702216448, 11802.704932376759, 12050.397480125783, 12162.817557736464, 12565.246085234916, 12673.683157920757, 12719.491220351452, 12818.294993775431, 13560.660983475493, 13634.318284085557, 13999.860001399855, 12020.278783273032, 12447.93776031114, 12848.35758212067, 12852.357382130669, 12918.677033074247, 12105.33420661872, 12133.211341263142, 12368.0, 12588.559400420942, 12599.0, 12093.213941093802, 12188.939055304667, 12395.256284622952, 12413.131080824256, 12912.870871291543, 9970.252231082553, 10043.598256069676, 10165.491725413553, 10266.743331416774, 10671.46642667879, 9291.073311906728, 9697.32118751709, 9702.126808587262, 9870.309078364733, 9878.259130565093], "value": 7392.641429386881, "unit": "score"}], "name": "dromaeo_css", "value": 7222.088681582204, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 111.0, 107.0, 111.0, 112.0, 112.0, 113.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 119.0, 187.0, 122.0, 122.0, 150.0, 117.0, 162.0, 130.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 159.0, 159.0, 159.0, 162.0, 161.0, 160.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 114.0, 76.0, 77.0, 74.0, 75.0, 103.0, 74.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 129.0, 85.0, 85.0, 85.0, 123.0, 84.0, 85.0, 129.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 149.0, 146.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 49.0, 51.0, 49.0, 51.0, 51.0, 51.0, 49.0, 51.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 62.0, 60.0, 62.0, 62.0, 62.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 83.0, 78.0, 78.0, 82.0, 77.0, 78.0, 80.0, 80.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 119.0, 122.0, 120.0, 124.0, 124.0, 119.0, 161.0, 124.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 162.0, 163.0, 167.0, 166.0, 163.0, 164.0, 165.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 64.0, 64.0, 63.0, 63.0, 66.0, 63.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1491.1, "alertThreshold": 2.0}]} 19:29:24 INFO - Return code: 0 19:29:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:29:24 INFO - # TBPL SUCCESS # 19:29:24 INFO - Running post-action listener: _resource_record_post_action 19:29:24 INFO - [mozharness: 2016-08-17 02:29:24.440389Z] Finished run-tests step (success) 19:29:24 INFO - Running post-run listener: _resource_record_post_run 19:29:24 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 421281792; Read time: 44; Write time: 357096 19:29:24 INFO - TinderboxPrint: CPU usage
13.0% 19:29:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 44 19:29:24 INFO - TinderboxPrint: I/O write bytes / time
421,281,792 / 357,096 19:29:24 INFO - TinderboxPrint: CPU idle
8,281.8 (87.0%) 19:29:24 INFO - TinderboxPrint: CPU user
1,194.3 (12.5%) 19:29:24 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:24 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 118108160; Read time: 0; Write time: 118076 19:29:24 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291868672; Read time: 44; Write time: 236644 19:29:25 INFO - Running post-run listener: _upload_blobber_files 19:29:25 INFO - Blob upload gear active. 19:29:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:29:25 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:26 INFO - (blobuploader) - INFO - Done attempting. 19:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:27 INFO - (blobuploader) - INFO - Done attempting. 19:29:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:30 INFO - (blobuploader) - INFO - Done attempting. 19:29:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:30 INFO - Return code: 0 19:29:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fde396cd29bdfa5c9ca1d5eac73d7f9ce9d71798addaa4caef609b28d628ad5829f3aa1ef431709eab2698c54e7032e3e5dea88a42cf9208ff074ccab2547c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f13dc92a457764a8c423820156bcbd6f6adbb60ab26c2b0390e06c89ee4301c977a24890c371832aa93bbc56705d7a54cbc95020006748071859ec9385b8e37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2fc2827da8008c0f8faa87b8f3dfff847ce9a8b5c9c96f5541d7344c44e4a7f6602ce05eb5c5ffc728896d34b7e1f7f1d52bae0fd9b2be4ca13a0c94ec76ce3"} 19:29:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:30 INFO - Contents: 19:29:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fde396cd29bdfa5c9ca1d5eac73d7f9ce9d71798addaa4caef609b28d628ad5829f3aa1ef431709eab2698c54e7032e3e5dea88a42cf9208ff074ccab2547c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f13dc92a457764a8c423820156bcbd6f6adbb60ab26c2b0390e06c89ee4301c977a24890c371832aa93bbc56705d7a54cbc95020006748071859ec9385b8e37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2fc2827da8008c0f8faa87b8f3dfff847ce9a8b5c9c96f5541d7344c44e4a7f6602ce05eb5c5ffc728896d34b7e1f7f1d52bae0fd9b2be4ca13a0c94ec76ce3"} 19:29:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:30 INFO - Copying logs to upload dir... 19:29:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.214610 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-16 19:29:30.502648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 19:29:30.506552) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fde396cd29bdfa5c9ca1d5eac73d7f9ce9d71798addaa4caef609b28d628ad5829f3aa1ef431709eab2698c54e7032e3e5dea88a42cf9208ff074ccab2547c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f13dc92a457764a8c423820156bcbd6f6adbb60ab26c2b0390e06c89ee4301c977a24890c371832aa93bbc56705d7a54cbc95020006748071859ec9385b8e37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2fc2827da8008c0f8faa87b8f3dfff847ce9a8b5c9c96f5541d7344c44e4a7f6602ce05eb5c5ffc728896d34b7e1f7f1d52bae0fd9b2be4ca13a0c94ec76ce3"} build_url:https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011158 build_url: 'https://queue.taskcluster.net/v1/task/SL93HaUXQeejthDli2Epjw/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/Firefox/sha512/c2fde396cd29bdfa5c9ca1d5eac73d7f9ce9d71798addaa4caef609b28d628ad5829f3aa1ef431709eab2698c54e7032e3e5dea88a42cf9208ff074ccab2547c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f13dc92a457764a8c423820156bcbd6f6adbb60ab26c2b0390e06c89ee4301c977a24890c371832aa93bbc56705d7a54cbc95020006748071859ec9385b8e37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2fc2827da8008c0f8faa87b8f3dfff847ce9a8b5c9c96f5541d7344c44e4a7f6602ce05eb5c5ffc728896d34b7e1f7f1d52bae0fd9b2be4ca13a0c94ec76ce3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 19:29:30.531554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:29:30.531877) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471397390.975638-1811994213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:29:30.614410) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 19:29:30.614718) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 19:29:30.615026) ========= ========= Total master_lag: 0.17 =========