builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1472559831.13 results: success (0) buildid: 20160830023002 builduid: 8d60539343b04c5d94278cdf7dce4df4 revision: 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.130616) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.131062) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.131338) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.150471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.150752) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 05:23: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-08-30 05:23:51 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.258467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.258783) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019324 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.308679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.309111) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 05:23:51,657 truncating revision to first 12 chars 2016-08-30 05:23:51,657 Setting DEBUG logging. 2016-08-30 05:23:51,657 attempt 1/10 2016-08-30 05:23:51,657 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2c763524c0b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 05:23:51,952 unpacking tar archive at: fx-team-2c763524c0b6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612394 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.935187) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.935574) ========= script_repo_revision: 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.936020) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.936299) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 05:23:51.950363) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-30 05:23:51.950646) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:23:52 INFO - MultiFileLogger online at 20160830 05:23:52 in /builds/slave/test 05:23:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:23:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:52 INFO - {'append_to_log': False, 05:23:52 INFO - 'base_work_dir': '/builds/slave/test', 05:23:52 INFO - 'blob_upload_branch': 'Fx-Team', 05:23:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:52 INFO - 'branch': 'Fx-Team', 05:23:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:52 INFO - 'config_files': ('talos/linux_config.py',), 05:23:52 INFO - 'default_actions': ('clobber', 05:23:52 INFO - 'read-buildbot-config', 05:23:52 INFO - 'download-and-extract', 05:23:52 INFO - 'populate-webroot', 05:23:52 INFO - 'create-virtualenv', 05:23:52 INFO - 'install', 05:23:52 INFO - 'run-tests'), 05:23:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:52 INFO - 'download_minidump_stackwalk': True, 05:23:52 INFO - 'download_symbols': 'ondemand', 05:23:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:52 INFO - 'installer_path': 'installer.exe', 05:23:52 INFO - 'log_level': 'info', 05:23:52 INFO - 'log_name': 'talos', 05:23:52 INFO - 'log_to_console': True, 05:23:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:23:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:23:52 INFO - 'opt_config_files': (), 05:23:52 INFO - 'pip_index': False, 05:23:52 INFO - 'sps_profile': False, 05:23:52 INFO - 'sps_profile_interval': 0, 05:23:52 INFO - 'suite': 'dromaeojs', 05:23:52 INFO - 'system_bits': '32', 05:23:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:52 INFO - 'title': 'talos-linux64-ix-011', 05:23:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:52 INFO - 'use_talos_json': True, 05:23:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:52 INFO - 'work_dir': 'build'} 05:23:52 INFO - [mozharness: 2016-08-30 12:23:52.391374Z] Running clobber step. 05:23:52 INFO - Running pre-action listener: _resource_record_pre_action 05:23:52 INFO - Running main action method: clobber 05:23:52 INFO - rmtree: /builds/slave/test/build 05:23:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:52 INFO - Running post-action listener: _resource_record_post_action 05:23:52 INFO - [mozharness: 2016-08-30 12:23:52.853828Z] Finished clobber step (success) 05:23:52 INFO - [mozharness: 2016-08-30 12:23:52.853930Z] Running read-buildbot-config step. 05:23:52 INFO - Running pre-action listener: _resource_record_pre_action 05:23:52 INFO - Running main action method: read_buildbot_config 05:23:52 INFO - Using buildbot properties: 05:23:52 INFO - { 05:23:52 INFO - "project": "", 05:23:52 INFO - "product": "firefox", 05:23:52 INFO - "script_repo_revision": "production", 05:23:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:23:52 INFO - "repository": "", 05:23:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:23:52 INFO - "buildid": "20160830023002", 05:23:52 INFO - "pgo_build": "True", 05:23:52 INFO - "basedir": "/builds/slave/test", 05:23:52 INFO - "buildnumber": 1018, 05:23:52 INFO - "slavename": "talos-linux64-ix-011", 05:23:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:23:52 INFO - "platform": "ubuntu64_hw", 05:23:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:23:52 INFO - "branch": "fx-team", 05:23:52 INFO - "repo_path": "integration/fx-team", 05:23:52 INFO - "revision": "2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0", 05:23:52 INFO - "stage_platform": "linux64-pgo", 05:23:52 INFO - "builduid": "8d60539343b04c5d94278cdf7dce4df4", 05:23:52 INFO - "slavebuilddir": "test" 05:23:52 INFO - } 05:23:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:23:52 INFO - Running post-action listener: _resource_record_post_action 05:23:52 INFO - [mozharness: 2016-08-30 12:23:52.856452Z] Finished read-buildbot-config step (success) 05:23:52 INFO - [mozharness: 2016-08-30 12:23:52.856541Z] Running download-and-extract step. 05:23:52 INFO - Running pre-action listener: _resource_record_pre_action 05:23:52 INFO - Running main action method: download_and_extract 05:23:52 INFO - mkdir: /builds/slave/test/build/tests 05:23:52 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')]} 05:23:52 INFO - trying https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:23:52 INFO - Downloading https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:53 INFO - Downloaded 1361 bytes. 05:23:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:23:53 INFO - Using the following test package requirements: 05:23:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:23:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'jsshell-linux-x86_64.zip'], 05:23:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:23:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:23:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23: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')]} 05:23:53 INFO - trying https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:23:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:54 INFO - Downloaded 21885904 bytes. 05:23: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 05:23:58 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 05:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:58 INFO - trying https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:23:58 INFO - Downloading https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:23:59 INFO - Downloaded 21885904 bytes. 05:23: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 05:24:03 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')]} 05:24:03 INFO - trying https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:24:03 INFO - Downloading https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:24:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:24:05 INFO - Downloaded 14413998 bytes. 05:24: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 05:24:05 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')]} 05:24:05 INFO - trying https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:24:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:24:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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 05:24:07 INFO - Downloaded 59720378 bytes. 05:24:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:24:07 INFO - mkdir: /builds/slave/test/properties 05:24:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:24:07 INFO - Writing to file /builds/slave/test/properties/build_url 05:24:07 INFO - Contents: 05:24:07 INFO - build_url:https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:24:08 INFO - Running post-action listener: _resource_record_post_action 05:24:08 INFO - Running post-action listener: set_extra_try_arguments 05:24:08 INFO - [mozharness: 2016-08-30 12:24:08.029435Z] Finished download-and-extract step (success) 05:24:08 INFO - [mozharness: 2016-08-30 12:24:08.029666Z] Running populate-webroot step. 05:24:08 INFO - Running pre-action listener: _resource_record_pre_action 05:24:08 INFO - Running main action method: populate_webroot 05:24:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:24:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:24:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:24:08 INFO - '--tppagecycles', 05:24:08 INFO - '7'], 05:24:08 INFO - 'tests': ['tsvgm']}}, 05:24:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:24:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:24:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:24:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:24:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['damp', 'tps']}, 05:24:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'tests': ['damp', 'tps']}, 05:24:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['dromaeo_dom']}, 05:24:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:24:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['basic_compositor_video']}, 05:24:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:24:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'other-e10s': {'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['a11yr', 05:24:08 INFO - 'ts_paint', 05:24:08 INFO - 'tpaint', 05:24:08 INFO - 'sessionrestore', 05:24:08 INFO - 'sessionrestore_no_auto_restore', 05:24:08 INFO - 'tabpaint']}, 05:24:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['tsvgx', 05:24:08 INFO - 'tsvgr_opacity', 05:24:08 INFO - 'tart', 05:24:08 INFO - 'tscrollx', 05:24:08 INFO - 'cart']}, 05:24:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:24:08 INFO - 'tsvgr_opacity', 05:24:08 INFO - 'tart', 05:24:08 INFO - 'tscrollx', 05:24:08 INFO - 'cart']}, 05:24:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'talos_options': ['--disable-e10s'], 05:24:08 INFO - 'tests': ['tp5o']}, 05:24:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'tests': ['tp5o']}, 05:24:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'talos_options': ['--disable-e10s', 05:24:08 INFO - '--xperf_path', 05:24:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:08 INFO - 'tests': ['tp5n']}, 05:24:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:08 INFO - 'talos_options': ['--xperf_path', 05:24:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:08 INFO - 'tests': ['tp5n']}}, 05:24:08 INFO - 'talos.zip': {'path': '', 05:24:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:24:08 INFO - Running post-action listener: _resource_record_post_action 05:24:08 INFO - [mozharness: 2016-08-30 12:24:08.055285Z] Finished populate-webroot step (success) 05:24:08 INFO - [mozharness: 2016-08-30 12:24:08.055375Z] Running create-virtualenv step. 05:24:08 INFO - Running pre-action listener: _resource_record_pre_action 05:24:08 INFO - Running main action method: create_virtualenv 05:24:08 INFO - Creating virtualenv /builds/slave/test/build/venv 05:24: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 05:24:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:24:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:24:08 INFO - Using real prefix '/usr' 05:24:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:10 INFO - Installing pip.................done. 05:24:10 INFO - Return code: 0 05:24:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:10 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:10 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 05:24:10 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 05:24:10 INFO - Using env: {'DISPLAY': ':0', 05:24:10 INFO - 'HOME': '/home/cltbld', 05:24:10 INFO - 'LANG': 'en_US.UTF-8', 05:24:10 INFO - 'LANGUAGE': 'en_US:en', 05:24:10 INFO - 'LOGNAME': 'cltbld', 05:24:10 INFO - 'MAIL': '/var/mail/cltbld', 05:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:10 INFO - 'MOZ_NO_REMOTE': '1', 05:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:10 INFO - 'NO_EM_RESTART': '1', 05:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:10 INFO - 'PWD': '/builds/slave/test', 05:24:10 INFO - 'SHELL': '/bin/bash', 05:24:10 INFO - 'SHLVL': '1', 05:24:10 INFO - 'TERM': 'linux', 05:24:10 INFO - 'TMOUT': '86400', 05:24:10 INFO - 'USER': 'cltbld', 05:24:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:10 INFO - '_': '/tools/buildbot/bin/python'} 05:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:10 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 05:24:10 INFO - Cleaning up... 05:24:10 INFO - Return code: 0 05:24:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:10 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:10 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 05:24:10 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 05:24:10 INFO - Using env: {'DISPLAY': ':0', 05:24:10 INFO - 'HOME': '/home/cltbld', 05:24:10 INFO - 'LANG': 'en_US.UTF-8', 05:24:10 INFO - 'LANGUAGE': 'en_US:en', 05:24:10 INFO - 'LOGNAME': 'cltbld', 05:24:10 INFO - 'MAIL': '/var/mail/cltbld', 05:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:10 INFO - 'MOZ_NO_REMOTE': '1', 05:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:10 INFO - 'NO_EM_RESTART': '1', 05:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:10 INFO - 'PWD': '/builds/slave/test', 05:24:10 INFO - 'SHELL': '/bin/bash', 05:24:10 INFO - 'SHLVL': '1', 05:24:10 INFO - 'TERM': 'linux', 05:24:10 INFO - 'TMOUT': '86400', 05:24:10 INFO - 'USER': 'cltbld', 05:24:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:10 INFO - '_': '/tools/buildbot/bin/python'} 05:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:11 INFO - Downloading/unpacking psutil>=3.1.1 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:13 INFO - Installing collected packages: psutil 05:24:13 INFO - Running setup.py install for psutil 05:24:13 INFO - building 'psutil._psutil_linux' extension 05:24: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 05:24: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 05:24:13 INFO - building 'psutil._psutil_posix' extension 05:24: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 05:24: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:24:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:13 INFO - Successfully installed psutil 05:24:13 INFO - Cleaning up... 05:24:13 INFO - Return code: 0 05:24:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:24:13 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')]} 05:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:13 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')]} 05:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:13 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:13 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 05:24:13 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 05:24:13 INFO - Using env: {'DISPLAY': ':0', 05:24:13 INFO - 'HOME': '/home/cltbld', 05:24:13 INFO - 'LANG': 'en_US.UTF-8', 05:24:13 INFO - 'LANGUAGE': 'en_US:en', 05:24:13 INFO - 'LOGNAME': 'cltbld', 05:24:13 INFO - 'MAIL': '/var/mail/cltbld', 05:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:13 INFO - 'MOZ_NO_REMOTE': '1', 05:24:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:13 INFO - 'NO_EM_RESTART': '1', 05:24:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:13 INFO - 'PWD': '/builds/slave/test', 05:24:13 INFO - 'SHELL': '/bin/bash', 05:24:13 INFO - 'SHLVL': '1', 05:24:13 INFO - 'TERM': 'linux', 05:24:13 INFO - 'TMOUT': '86400', 05:24:13 INFO - 'USER': 'cltbld', 05:24:13 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:13 INFO - '_': '/tools/buildbot/bin/python'} 05:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:24:13 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 05:24:13 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 05:24:13 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 05:24:13 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 05:24:13 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 05:24:13 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 05:24:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:24:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:24:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:24:15 INFO - Installing collected packages: mozsystemmonitor 05:24:15 INFO - Running setup.py install for mozsystemmonitor 05:24:15 INFO - Successfully installed mozsystemmonitor 05:24:15 INFO - Cleaning up... 05:24:15 INFO - Return code: 0 05:24:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:15 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:15 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 05:24:15 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 05:24:15 INFO - Using env: {'DISPLAY': ':0', 05:24:15 INFO - 'HOME': '/home/cltbld', 05:24:15 INFO - 'LANG': 'en_US.UTF-8', 05:24:15 INFO - 'LANGUAGE': 'en_US:en', 05:24:15 INFO - 'LOGNAME': 'cltbld', 05:24:15 INFO - 'MAIL': '/var/mail/cltbld', 05:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:15 INFO - 'MOZ_NO_REMOTE': '1', 05:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:15 INFO - 'NO_EM_RESTART': '1', 05:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:15 INFO - 'PWD': '/builds/slave/test', 05:24:15 INFO - 'SHELL': '/bin/bash', 05:24:15 INFO - 'SHLVL': '1', 05:24:15 INFO - 'TERM': 'linux', 05:24:15 INFO - 'TMOUT': '86400', 05:24:15 INFO - 'USER': 'cltbld', 05:24:15 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:15 INFO - '_': '/tools/buildbot/bin/python'} 05:24:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:16 INFO - Downloading/unpacking jsonschema==2.5.1 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:24:17 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 05:24:17 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 05:24:17 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 05:24:17 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 05:24:17 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 05:24:17 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 05:24:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:18 INFO - warning: no files found matching '*.txt' 05:24:18 INFO - no previously-included directories found matching 'build' 05:24:18 INFO - no previously-included directories found matching 'dist' 05:24:18 INFO - no previously-included directories found matching '.git*' 05:24:18 INFO - Installing collected packages: jsonschema, functools32 05:24:18 INFO - Running setup.py install for functools32 05:24:18 INFO - warning: no files found matching '*.txt' 05:24:18 INFO - no previously-included directories found matching 'build' 05:24:18 INFO - no previously-included directories found matching 'dist' 05:24:18 INFO - no previously-included directories found matching '.git*' 05:24:18 INFO - Successfully installed jsonschema functools32 05:24:18 INFO - Cleaning up... 05:24:18 INFO - Return code: 0 05:24:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:24:18 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')]} 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:18 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')]} 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:18 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:18 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 05:24:18 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 05:24:18 INFO - Using env: {'DISPLAY': ':0', 05:24:18 INFO - 'HOME': '/home/cltbld', 05:24:18 INFO - 'LANG': 'en_US.UTF-8', 05:24:18 INFO - 'LANGUAGE': 'en_US:en', 05:24:18 INFO - 'LOGNAME': 'cltbld', 05:24:18 INFO - 'MAIL': '/var/mail/cltbld', 05:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:18 INFO - 'MOZ_NO_REMOTE': '1', 05:24:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:18 INFO - 'NO_EM_RESTART': '1', 05:24:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:18 INFO - 'PWD': '/builds/slave/test', 05:24:18 INFO - 'SHELL': '/bin/bash', 05:24:18 INFO - 'SHLVL': '1', 05:24:18 INFO - 'TERM': 'linux', 05:24:18 INFO - 'TMOUT': '86400', 05:24:18 INFO - 'USER': 'cltbld', 05:24:18 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:18 INFO - '_': '/tools/buildbot/bin/python'} 05:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:24:18 INFO - Cleaning up... 05:24:18 INFO - Return code: 0 05:24:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:24:18 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')]} 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:18 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')]} 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:18 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:18 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 05:24:18 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 05:24:18 INFO - Using env: {'DISPLAY': ':0', 05:24:18 INFO - 'HOME': '/home/cltbld', 05:24:18 INFO - 'LANG': 'en_US.UTF-8', 05:24:18 INFO - 'LANGUAGE': 'en_US:en', 05:24:18 INFO - 'LOGNAME': 'cltbld', 05:24:18 INFO - 'MAIL': '/var/mail/cltbld', 05:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:18 INFO - 'MOZ_NO_REMOTE': '1', 05:24:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:18 INFO - 'NO_EM_RESTART': '1', 05:24:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:18 INFO - 'PWD': '/builds/slave/test', 05:24:18 INFO - 'SHELL': '/bin/bash', 05:24:18 INFO - 'SHLVL': '1', 05:24:18 INFO - 'TERM': 'linux', 05:24:18 INFO - 'TMOUT': '86400', 05:24:18 INFO - 'USER': 'cltbld', 05:24:18 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:18 INFO - '_': '/tools/buildbot/bin/python'} 05:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:24:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:24:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:21 INFO - Downloading docopt-0.6.1.tar.gz 05:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:24:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:24:21 INFO - Running setup.py install for blobuploader 05:24:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:24:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:24:21 INFO - Running setup.py install for requests 05:24:21 INFO - Running setup.py install for docopt 05:24:22 INFO - Successfully installed blobuploader requests docopt 05:24:22 INFO - Cleaning up... 05:24:22 INFO - Return code: 0 05:24:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24: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 05:24: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 05:24:22 INFO - Using env: {'DISPLAY': ':0', 05:24:22 INFO - 'HOME': '/home/cltbld', 05:24:22 INFO - 'LANG': 'en_US.UTF-8', 05:24:22 INFO - 'LANGUAGE': 'en_US:en', 05:24:22 INFO - 'LOGNAME': 'cltbld', 05:24:22 INFO - 'MAIL': '/var/mail/cltbld', 05:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:22 INFO - 'MOZ_NO_REMOTE': '1', 05:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:22 INFO - 'NO_EM_RESTART': '1', 05:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:22 INFO - 'PWD': '/builds/slave/test', 05:24:22 INFO - 'SHELL': '/bin/bash', 05:24:22 INFO - 'SHLVL': '1', 05:24:22 INFO - 'TERM': 'linux', 05:24:22 INFO - 'TMOUT': '86400', 05:24:22 INFO - 'USER': 'cltbld', 05:24:22 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:22 INFO - '_': '/tools/buildbot/bin/python'} 05:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:22 INFO - Running setup.py (path:/tmp/pip-423Q5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:22 INFO - Running setup.py (path:/tmp/pip-hkufqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:22 INFO - Running setup.py (path:/tmp/pip-qFVEux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:22 INFO - Running setup.py (path:/tmp/pip-UjRzZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:22 INFO - Running setup.py (path:/tmp/pip-4AX192-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:22 INFO - Running setup.py (path:/tmp/pip-hxd5_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:22 INFO - Running setup.py (path:/tmp/pip-VdXZxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:22 INFO - Running setup.py (path:/tmp/pip-MQyt6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:22 INFO - Running setup.py (path:/tmp/pip-Z4XGjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:22 INFO - Running setup.py (path:/tmp/pip-Qw6vMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:23 INFO - Running setup.py (path:/tmp/pip-IFL6w3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:23 INFO - Running setup.py (path:/tmp/pip-sUoVKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:23 INFO - Running setup.py (path:/tmp/pip-5nBpNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:23 INFO - Running setup.py (path:/tmp/pip-USmkuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:23 INFO - Running setup.py (path:/tmp/pip-C_bjz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:23 INFO - Running setup.py (path:/tmp/pip-VXQ9bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:23 INFO - Running setup.py (path:/tmp/pip-JqDNte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:23 INFO - Running setup.py install for manifestparser 05:24:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:23 INFO - Running setup.py install for mozcrash 05:24:23 INFO - Running setup.py install for mozdebug 05:24:23 INFO - Running setup.py install for mozdevice 05:24:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:23 INFO - Running setup.py install for mozfile 05:24:24 INFO - Running setup.py install for mozhttpd 05:24:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozinfo 05:24:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozInstall 05:24:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozleak 05:24:24 INFO - Running setup.py install for mozlog 05:24:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for moznetwork 05:24:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozprocess 05:24:24 INFO - Running setup.py install for mozprofile 05:24:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozrunner 05:24:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:25 INFO - Running setup.py install for mozscreenshot 05:24:25 INFO - Running setup.py install for moztest 05:24:25 INFO - Running setup.py install for mozversion 05:24:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:24:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:24:25 INFO - Cleaning up... 05:24:25 INFO - Return code: 0 05:24:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:25 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:25 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 05:24:25 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 05:24:25 INFO - Using env: {'DISPLAY': ':0', 05:24:25 INFO - 'HOME': '/home/cltbld', 05:24:25 INFO - 'LANG': 'en_US.UTF-8', 05:24:25 INFO - 'LANGUAGE': 'en_US:en', 05:24:25 INFO - 'LOGNAME': 'cltbld', 05:24:25 INFO - 'MAIL': '/var/mail/cltbld', 05:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:25 INFO - 'MOZ_NO_REMOTE': '1', 05:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:25 INFO - 'NO_EM_RESTART': '1', 05:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:25 INFO - 'PWD': '/builds/slave/test', 05:24:25 INFO - 'SHELL': '/bin/bash', 05:24:25 INFO - 'SHLVL': '1', 05:24:25 INFO - 'TERM': 'linux', 05:24:25 INFO - 'TMOUT': '86400', 05:24:25 INFO - 'USER': 'cltbld', 05:24:25 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:25 INFO - '_': '/tools/buildbot/bin/python'} 05:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:25 INFO - Running setup.py (path:/tmp/pip-c45azI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:25 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)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:25 INFO - Running setup.py (path:/tmp/pip-GdpZUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:25 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)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:25 INFO - Running setup.py (path:/tmp/pip-ywdEAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:25 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)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:25 INFO - Running setup.py (path:/tmp/pip-sobrZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:25 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)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:25 INFO - Running setup.py (path:/tmp/pip-nnLF9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:25 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)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:25 INFO - Running setup.py (path:/tmp/pip-xWE9rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24: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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:26 INFO - Running setup.py (path:/tmp/pip-Id42tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24: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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:26 INFO - Running setup.py (path:/tmp/pip-KO1tkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24: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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:26 INFO - Running setup.py (path:/tmp/pip-1xPczr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24: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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:26 INFO - Running setup.py (path:/tmp/pip-7qpj8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:26 INFO - Running setup.py (path:/tmp/pip-ApKbj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:26 INFO - Running setup.py (path:/tmp/pip-YGEZxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:26 INFO - Running setup.py (path:/tmp/pip-iRP5Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:26 INFO - Running setup.py (path:/tmp/pip-RZQ4xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:26 INFO - Running setup.py (path:/tmp/pip-BbwKGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:26 INFO - Running setup.py (path:/tmp/pip-6GFCLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:26 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)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:26 INFO - Running setup.py (path:/tmp/pip-7FVT3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:26 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)) 05:24:26 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)) 05:24:26 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)) 05:24:26 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)) 05:24:26 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)) 05:24:26 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)) 05:24:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:28 INFO - Downloading blessings-1.6.tar.gz 05:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:28 INFO - Installing collected packages: blessings 05:24:28 INFO - Running setup.py install for blessings 05:24:28 INFO - Successfully installed blessings 05:24:28 INFO - Cleaning up... 05:24:28 INFO - Return code: 0 05:24:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:29 INFO - Reading from file tmpfile_stdout 05:24:29 INFO - Current package versions: 05:24:29 INFO - argparse == 1.2.1 05:24:29 INFO - blessings == 1.6 05:24:29 INFO - blobuploader == 1.2.4 05:24:29 INFO - docopt == 0.6.1 05:24:29 INFO - functools32 == 3.2.3-2 05:24:29 INFO - jsonschema == 2.5.1 05:24:29 INFO - manifestparser == 1.1 05:24:29 INFO - mozInstall == 1.12 05:24:29 INFO - mozcrash == 0.17 05:24:29 INFO - mozdebug == 0.1 05:24:29 INFO - mozdevice == 0.48 05:24:29 INFO - mozfile == 1.2 05:24:29 INFO - mozhttpd == 0.7 05:24:29 INFO - mozinfo == 0.9 05:24:29 INFO - mozleak == 0.1 05:24:29 INFO - mozlog == 3.3 05:24:29 INFO - moznetwork == 0.27 05:24:29 INFO - mozprocess == 0.23 05:24:29 INFO - mozprofile == 0.28 05:24:29 INFO - mozrunner == 6.12 05:24:29 INFO - mozscreenshot == 0.1 05:24:29 INFO - mozsystemmonitor == 0.3 05:24:29 INFO - moztest == 0.7 05:24:29 INFO - mozversion == 1.4 05:24:29 INFO - psutil == 3.1.1 05:24:29 INFO - requests == 1.2.3 05:24:29 INFO - wsgiref == 0.1.2 05:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24: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 05:24: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 05:24:29 INFO - Using env: {'DISPLAY': ':0', 05:24:29 INFO - 'HOME': '/home/cltbld', 05:24:29 INFO - 'LANG': 'en_US.UTF-8', 05:24:29 INFO - 'LANGUAGE': 'en_US:en', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MAIL': '/var/mail/cltbld', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SHLVL': '1', 05:24:29 INFO - 'TERM': 'linux', 05:24:29 INFO - 'TMOUT': '86400', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - '_': '/tools/buildbot/bin/python'} 05:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 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)) 05:24:29 INFO - Cleaning up... 05:24:29 INFO - Return code: 0 05:24:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:29 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 0x7f8f39faaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', '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 05:24:29 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 05:24:29 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 05:24:29 INFO - Using env: {'DISPLAY': ':0', 05:24:29 INFO - 'HOME': '/home/cltbld', 05:24:29 INFO - 'LANG': 'en_US.UTF-8', 05:24:29 INFO - 'LANGUAGE': 'en_US:en', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MAIL': '/var/mail/cltbld', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SHLVL': '1', 05:24:29 INFO - 'TERM': 'linux', 05:24:29 INFO - 'TMOUT': '86400', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - '_': '/tools/buildbot/bin/python'} 05:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:24:29 INFO - Cleaning up... 05:24:29 INFO - Return code: 0 05:24:29 INFO - Running post-action listener: _resource_record_post_action 05:24:29 INFO - Running post-action listener: _start_resource_monitoring 05:24:29 INFO - Starting resource monitoring. 05:24:29 INFO - [mozharness: 2016-08-30 12:24:29.627132Z] Finished create-virtualenv step (success) 05:24:29 INFO - [mozharness: 2016-08-30 12:24:29.627633Z] Running install step. 05:24:29 INFO - Running pre-action listener: _resource_record_pre_action 05:24:29 INFO - Running main action method: install 05:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:29 INFO - Reading from file tmpfile_stdout 05:24:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:29 INFO - Reading from file tmpfile_stdout 05:24:29 INFO - Output received: 05:24:29 INFO - Usage: mozinstall [options] installer 05:24:29 INFO - Options: 05:24:29 INFO - -h, --help show this help message and exit 05:24:29 INFO - -d DEST, --destination=DEST 05:24:29 INFO - Directory to install application into. [default: 05:24:29 INFO - "/builds/slave/test"] 05:24:29 INFO - --app=APP Application being installed. [default: firefox] 05:24:29 INFO - mkdir: /builds/slave/test/build/application 05:24:29 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'] 05:24:29 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 05:24:46 INFO - Reading from file tmpfile_stdout 05:24:46 INFO - Output received: 05:24:46 INFO - /builds/slave/test/build/application/firefox/firefox 05:24:46 INFO - Running post-action listener: _resource_record_post_action 05:24:46 INFO - [mozharness: 2016-08-30 12:24:46.948761Z] Finished install step (success) 05:24:46 INFO - [mozharness: 2016-08-30 12:24:46.949100Z] Running run-tests step. 05:24:46 INFO - Running pre-action listener: _resource_record_pre_action 05:24:46 INFO - Running main action method: run_tests 05:24:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:46 INFO - Python 2.7.3 05:24:46 INFO - Return code: 0 05:24:46 INFO - grabbing minidump binary from tooltool 05:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:46 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 0x12f6370>, '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 0x12f98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f9d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:46 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 05:24:46 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 05:24:46 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 05:24:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:47 INFO - Return code: 0 05:24:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:24:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:24:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:24:47 INFO - Using env: {'DISPLAY': ':0', 05:24:47 INFO - 'HOME': '/home/cltbld', 05:24:47 INFO - 'LANG': 'en_US.UTF-8', 05:24:47 INFO - 'LANGUAGE': 'en_US:en', 05:24:47 INFO - 'LOGNAME': 'cltbld', 05:24:47 INFO - 'MAIL': '/var/mail/cltbld', 05:24:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:47 INFO - 'MOZ_NO_REMOTE': '1', 05:24:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:47 INFO - 'NO_EM_RESTART': '1', 05:24:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:47 INFO - 'PWD': '/builds/slave/test', 05:24:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:47 INFO - 'SHELL': '/bin/bash', 05:24:47 INFO - 'SHLVL': '1', 05:24:47 INFO - 'TERM': 'linux', 05:24:47 INFO - 'TMOUT': '86400', 05:24:47 INFO - 'USER': 'cltbld', 05:24:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914', 05:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:47 INFO - '_': '/tools/buildbot/bin/python'} 05:24:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:24:47 INFO - mozversion application_buildid: 20160830023002 05:24:47 INFO - mozversion application_changeset: 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 05:24:47 INFO - mozversion application_display_name: Nightly 05:24:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:47 INFO - mozversion application_name: Firefox 05:24:47 INFO - mozversion application_remotingname: firefox 05:24:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:24:47 INFO - mozversion application_vendor: Mozilla 05:24:47 INFO - mozversion application_version: 51.0a1 05:24:47 INFO - mozversion platform_buildid: 20160830023002 05:24:47 INFO - mozversion platform_changeset: 2c763524c0b6e92987fb18fc6e6cdd05fa9ae6f0 05:24:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:24:47 INFO - mozversion platform_version: 51.0a1 05:24:47 INFO - using testdate: 1472559887 05:24:47 INFO - actual date: 1472559887 05:24:47 INFO - starting webserver on 'localhost:43560' 05:24:47 INFO - SUITE-START | Running 2 tests 05:24:47 INFO - TEST-START | dromaeo_css 05:24:47 INFO - operating with platform_type : linux_ 05:24:47 INFO - Initialising browser for dromaeo_css test... 05:24:47 INFO - TEST-INFO | started process 31286 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqRAs8J/profile http://localhost:43560/getInfo.html) 05:24:52 INFO - PROCESS | 31286 | __metrics Screen width/height:1600/1200 05:24:52 INFO - PROCESS | 31286 | colorDepth:24 05:24:52 INFO - PROCESS | 31286 | Browser inner width/height: 1024/697 05:24:52 INFO - PROCESS | 31286 | __metrics 05:24:52 INFO - PROCESS | 31286 | 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" 05:24:52 INFO - PROCESS | 31286 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:53 INFO - PROCESS | 31286 | console.error: PushService: 05:24:53 INFO - PROCESS | 31286 | stateChangeProcessEnqueue: Error transitioning state 05:24:53 INFO - PROCESS | 31286 | UnknownError 05:24:54 INFO - TEST-INFO | 31286: exit 0 05:24:54 INFO - Browser initialized. 05:24:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:54 INFO - TEST-INFO | started process 31369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqRAs8J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:05 INFO - PROCESS | 31369 | RSS: Main: 180129792 05:25:05 INFO - PROCESS | 31369 | 05:25:57 INFO - PROCESS | 31369 | 1472559957628 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] 05:28:07 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43560/tests/dromaeo/cssquery-ext.html) 05:28:08 INFO - PROCESS | 31369 | RSS: Main: 301289472 05:28:08 INFO - PROCESS | 31369 | 05:31:10 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-ext.html (next: http://localhost:43560/tests/dromaeo/cssquery-jquery.html) 05:31:11 INFO - PROCESS | 31369 | RSS: Main: 274239488 05:31:11 INFO - PROCESS | 31369 | 05:34:13 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43560/tests/dromaeo/cssquery-mootools.html) 05:34:13 INFO - PROCESS | 31369 | RSS: Main: 216133632 05:34:13 INFO - PROCESS | 31369 | 05:37:15 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43560/tests/dromaeo/cssquery-prototype.html) 05:37:16 INFO - PROCESS | 31369 | RSS: Main: 270729216 05:37:16 INFO - PROCESS | 31369 | 05:40:19 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43560/tests/dromaeo/cssquery-yui.html) 05:40:19 INFO - PROCESS | 31369 | RSS: Main: 216952832 05:40:19 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | Cycle 1(1): loaded http://localhost:43560/tests/dromaeo/cssquery-yui.html (next: http://localhost:43560/tests/dromaeo/cssquery-dojo.html) 05:43:22 INFO - PROCESS | 31369 | RSS: Main: 309309440 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | __start_tp_report 05:43:22 INFO - PROCESS | 31369 | _x_x_mozilla_page_load 05:43:22 INFO - PROCESS | 31369 | _x_x_mozilla_page_load_details 05:43:22 INFO - PROCESS | 31369 | |i|pagename|runs| 05:43:22 INFO - PROCESS | 31369 | |0;dojo.html;2977.225921260472;2987.835669038203;3020.3959208158376;3035.2108451802524;3069.785115041948;5131.589472842173;5215.42630310666;5254.369475662926;5296;5335.199720041991;5252.763625636846;5318.494742999409;5336.30628018357;5342.839714808562;5387.003404370194;4737.478877323491;4801.783919723621;4809.975950120244;4826.372571565692;4992.975035124819;4894;4905.975470122645;4943.060818444502;4950.430700469438;4981.277714731362;4777.952220477803;4814.566688997972;4885.071836351082;4892.608591312686;4896.46138924718;4808.447028591721;4815.109204797123;4826.85519434414;4856.635752318555;4951.826686066006;4817.229243321087;4851.417829860439;4856.538628830255;4870.001649661846;4880.829170978998;5234.214867769827;5246.580273578143;5340;5383.623146379755;5420.91868621971;5641.097424412115;5674.404187560288;5683.119116536902;5691.971540142315;5748.281464816898;5709.942900570983;5711;5727.541796656299;5730.598858079936;5732.742026608813;5059.797608095671;5284.947150528484;5308.442613525563;5334.759935802898;5347.786088556492;5900.1739756434135;5900.940990590125;5903.114532820111;5908.084246941731;5957.4042595740075;5632.183333416631;5642.943570564242;5650;5650.63270887391;5664.405237450009;5521.1442226454965;5626.099824028136;5648.237487939075;5650.915236271459;5734.39788822176;5712.514436272881;5714.371419143891;5720.370759216483;5728.369879313273;5768.826935191951;5633.915491267634;5684.658920464785;5687.886242275132;5694.088945768657;5723.7424315905055;4253.63844073251;4347.5217726050105;4349.9130017399475;4388.451443569554;4396.560343965642;3914.432407300925;3923.588023257517;3946.881593552198;3953.78254196022;3959.762414255154;5274.26160337553;5277.788888444496;5278.445763194886;5284.180951952377;5327.38735045467;5669.044179757977;5712.200291959129;5712.942870571324;5715.571332150105;5744.569157313218;5385.811496597678;5440.211169380417;5451.48210919962;5479.75341109651;5493.368262649847;5000.924986125138;5005.199168133081;5009.574186194214;5011.624128190328;5062.569681577107;5672.4894759471845;5769.1634712967225;5826.6795326256615;5925.851853703692;5929.792457263975;5711.571632127524;5714.457126572969;5717.456841600041;5771.624844385269;5780.768769249183;5854.4730974210825;5859.677717725566;5869.647821130746;5871.500922421642;5871.500922421642;5402.405735369185;5549.1676248563035;5577.637453565505;5584.134459158837;5601.691906944994;5743.167240749985;5746.683932383592;5747.1379293106365;5788.189653448605;5811.53507719373;5610.4670056344585;5621.578381621476;5621.887562248814;5623;5623.156526521055;5440.510354068153;5457.945420545744;5467.507924286833;5610.887782244414;5619.353774315842;5502.504774570308;5507.366652834975;5511.669299842022;5561.6663000220115;5572.164175373727;5507.201455788808;5553.639013464273;5555.0834112371;5566.304211973504;5570.025245582088;5303.5757139429525;5421.322334708161;5435.646682965595;5461.644993075437;5518.862028449321 05:43:22 INFO - PROCESS | 31369 | |1;ext.html;552.8858822458473;581.8138195777351;597.8087012156109;609.3875654966757;609.5032548472997;31564.684353156463;32540.511892321632;34490.99999999997;42967.57032429675;43730.562694373046;45816.770916145375;46443;46448;46510.767446162805;46542.534574654244;8790.824183516326;8943.50810705411;8948.328875334377;8950.41822281553;8951.060138685441;8861.955690221539;8949.64201431942;8955.955220223923;8956.283497320199;8957.507337096456;8883.067277935821;8914.598843052077;8936.285097192242;8956.059613740561;8986.14631609996;8881.511516866602;8925.553722313858;8960.372773905829;8963.775905602412;8969.416987895831;8824.955875220583;8884.11158884113;8889.111088891059;8890.955545222298;8896.421732587361;42419.363709544385;42441.78779106116;42796.786016069724;42798.1440371194;42812;3667.905824039653;3693.3905905525558;3736.532933383327;3817.938313310809;4034.7377420467574;31961;32084.518732219036;32126.678733212837;32379.6762032379;32728.345433091443;4274.444322238121;4389.824407023715;4414.161309351213;4418.668599855024;4512.684112112153;252875;253021;254626;254799;254857.31997520363;57622;58087;58156.41843581595;58237;58262;52474.47525524772;58253.417465825645;59490.70254648699;59822.10266846001;61566;14896.255187240595;14901.180435076174;14915.477958271409;14925.477608283658;14932.850671493363;12758.68103297425;12838.55065072718;12839.293838838954;12862.421191046426;12867.356632168354;15116.848831511765;16821.83178168227;17295.74056389155;17403.47789566315;17822.37621683235;5981.910271345933;6003.099535069775;6037.094435834572;6053.4551890330085;6057.182280392179;972.3825370889537;1019.2508506247901;1031.6389263757624;1039.2569312941284;1063.7978784031009;30756.69243307583;31804.363912721616;31839.840800795846;32009.51985720216;32426;1055.4089709762557;1058.957641694339;1062.235190662737;1099.483242875847;1124.3647339253296;15078.849211507964;16398.83601163973;16501.669966600602;16575.41986030483;16758.24587893548;563817;563820;563830;564510;567846;56963.715181423824;56980.43019569751;57012.71493642505;57037;57133.71433142808;1241.6026871401066;1363.5704753002772;1365.6790654196257;1368.9452421903013;1398.7342404943029;30448.543271851384;31809;32580;32964;33575;48462.51537484721;49078.75460622674;49102;49111.75444122757;49118.508814911394;387120;389146;389182;389466;390103;24887.87556062208;25332.366690832878;25619.743802561738;25693.100741473958;29225.56161657534;48041;48377.758111209216;48832;48863.755681221366;50056.249156261954;40080.3987940187;40834.387484187166;40915.7954210227;41152;42576;40598.59401406066;40769.38845917373;40914;42614;42887.78556107199;39595.406068908414;40196.39705404363;40241.79879100586;40603;41120.38319425151;35235;36928.44607330946;37114.62885371112;37255.25489490249;37386;27868.721312786613;27875.16374508768;28115.23603222326;28354.85822570874;28404.234072298947 05:43:22 INFO - PROCESS | 31369 | |2;jquery.html;382.91193025604093;395.2805893274242;398.2115411485259;404.58327922240096;421.9472565929259;15951.920240398797;18925.716114258295;19269.903650481745;19983.400497985047;20020;19492.70760938587;19677.311294104707;19770.802291977077;19937.401877943685;20275.290364837198;5305;5762.971185144069;5943.3462319144855;5984.072468767348;5999.640021598718;5889.941100588982;5893.911591326134;5910.379410161936;5944.524438044946;5952.047672372421;5670.376258611549;5833.970830145843;5870.970645146768;5872.17789509469;5904.084866845646;5672.262605861211;5861.120831875211;5877.735501902424;5897.7346019429015;5906.468417842393;5218.76515556797;5255.789768409259;5257.894842103136;5260.053190425722;5260.947390526084;23664;23731.288061358013;23765.287041388787;23836.40408989772;23849.642255366183;2259.1979847154353;2316.247219653619;2382.38058104894;2447.8898449569633;2459.8155138364514;10404.427756473391;10511.211659125596;10586.15310775144;10809.459527023697;11089.83365249522;1960.6274807786617;1976.743023406948;1986.960260794776;1996.7204591357222;2021.5249416387137;57483.425165748224;72849;74640;85169.1283461901;88372;9515.857262141073;9865.901340986498;10087.596496140219;10096.141827944706;11093.55625774976;13583.796243056362;13584.184948903097;13646.795298070538;13648.522301719393;13653.795193072112;7776.883346749803;7909.802254943673;7943.880841787378;8047.798805029804;8140.389470789596;7441.85116297671;7677.808054798675;7779.533228006338;7990.880136797953;8254.793630159294;4150.896227594334;4176.394422808737;4215.028404974486;4340.153670034313;4396.252637051709;1993.5713821528152;2020.1212472574477;2025.3721346382374;2034.603252365804;2035.94910127248;853.923146916768;924.3021518753433;998.9900100999042;1017.8574999500075;1030.9123724483352;19460.610787784164;20157.596848062956;20184.091715872823;20201.898990504953;20279.391618251477;985.556499575194;987.7876256604865;997.6757553795046;1001.234055947201;1020.3061917895753;10544.736381590368;11241.325520468798;11261.549538018551;11448.255863368855;11497.770044599061;438467;442259;445768;451799;454120;95330.52334738281;97830;98022.50988745154;99209;100850.49574752226;980.6469670918464;1005.1255407795119;1044.38903241603;1064.6433444795925;1070.5824728355947;17255.82744172542;17519.21163547618;17934.040883254638;18013.369532066314;18278.634427311114;29272.853635732536;39016;40239;40905.79547102245;44023.55976440194;51695.483045169065;52644.21033684575;52908;59086.704566476896;61199;8528.786780330542;8730.69442569507;8752.256058234867;8904.688335908211;9127.77180570491;13046.478140874638;13648;13695;13881.167129972235;13896.791548126585;11941;11976.880231197925;12039.217450866015;12040.698982525508;12091.395430228278;11766.411679415824;11906.404679765803;11966.820497692368;12434;12563.309018004098;11734.119941004288;11794.174407791372;11867.228630139014;11907.285562866256;12463.937680311541;8683.522406267462;8698.608562614823;8734.737957861274;8879.422837515542;8956.596953137256;10185.23610729213;11520.94239528797;12241.490494448795;12250.387480626112;12300.630981070582 05:43:22 INFO - PROCESS | 31369 | |3;mootools.html;1297.344840855369;1443.2999995002426;1463.4950941925035;1575.3855996161499;1782.639724120034;8392.91607083929;8874.600642971065;8888.200061994412;9122.498262595554;9132.269418446525;8938.329625278115;9039;9070.092990700927;9071;9074.909250907489;2089.707440958267;2298.241580278504;2411.1801987324307;2435.6468312094735;2440.389902524369;2418.7702168293986;2456.91400800972;2459.409741662006;2480.8635525046116;2491.040949234549;2429.356220601542;2434.4279094412886;2447.2413551799;2447.3514518652573;2448.8040956723507;2331.7318508371586;2359.4337359033793;2373.394784330002;2412.3124909400735;2420.1045613123083;2414.0343862454984;2425.0421083672027;2427.7693619106158;2430.7447717989535;2432;8758;8920.955395223047;8940.955295223483;8966.327525435554;8967.327450441244;5266.815661451831;5386.78452861885;5397.8380648580605;5399.892002159974;5412.648177868426;3239.87040518379;3361.5966084069823;3394.100563350714;3396.5924089109217;3409.556757621518;1051.148569658579;1086.7065892209139;1087.0814162033148;1090.9236353455262;1095.4522738630685;12081.818772718329;12362.19645723034;12443.128980971245;12528;12542.937285313606;4491.528389519053;4582.312653102062;4767.380240568773;4776.880577985578;4781.474037855833;10590.258681892194;10591;10599.947000264949;10603.787924241473;10607.787844243227;5322.0952438085615;5340.279062326614;5343.278657381282;5345.572354211654;5346.198070289488;4309.461317335333;4824.324594556765;4869.975650121727;4911.533404326583;4956.553910148032;4104.305191425641;4106.404571337199;4226.429432026698;4254.744715317091;4297.957020429818;1303.5763376902544;1305.9934700326628;1316.380402998046;1350.59482155353;1375.2573610250354;986.363795351994;998.256299057207;1008.5612758450097;1008.6570566007446;1018.765683892694;6028.728707208098;6103.42017508336;6121.296050954108;6194.101855230966;6379.872402551923;480.7211817146047;487.55875932281344;491.04981860100105;506.8378119001884;510.83653230965734;4207.242696314632;4220.092680073799;4247.110036033997;4321.5678432157165;4383.561643835591;10619.94690026545;11049.447527623586;11105.05607023412;11115.499802508744;11118.666440006813;11033.83449248262;11034.117270618332;11046.33721976668;11051.613193538187;11056.447177641086;7796.961015194887;7939.960300198462;8155.592220389076;8246.134155913544;8336.791580210422;5984.820455386345;6304.779332723333;6305.5901366411035;6354.7140378684;6395.072714456471;9732.75668108303;10219.080282774288;10245.129164020844;10288.691339259834;10328.741781455523;10100;10308.12380947628;10354.72196116853;10450.059494645518;10450.059494645518;6826.726930922708;7607.087149542091;7737.3036426721865;7829.099653539688;7926.207379262028;8642.308615310634;8843.69047083349;8845.646174152962;8854.203121719076;8856.247218986458;7744;7763.72826951054;7889.5660738657625;7986.960065199637;8087.231712987257;7698.499597526143;7821.178776228414;7871.48835325702;7903.170167132368;7917.049954005556;7208.56748595086;7342.339189472973;7346.485745997729;7349.4487913407775;7355.411567074728;5200.2199670049795;5300.310959575385;5364.758585863724;5503;5555.166724991283;5415.620906536505;5431.348238211282;5439.619226654097;5513.2005859151;5539.584531160099 05:43:22 INFO - PROCESS | 31369 | |4;prototype.html;338.52944407273895;345.1474857102956;348.84650753668376;349.74267508307656;352.88884001539515;5155.510226528483;5183.559397451221;5333.413324534298;5366.155937353199;5377.354717433914;5157.432682404932;5181.378234611851;5187.48125187481;5242.108841496945;5252.133397989327;4157.625813676768;4162.271602469578;4164.229617520768;4166.958330416687;4191.350340697197;3935.5277366716036;4043.65628921542;4167.395727619493;4173.853915112972;4215.3044747616605;4021.4973128358956;4039.757614543122;4044.352903535435;4048.7975601219823;4049.7165198436123;4030.576789437111;4049.7367671101433;4055.1281474482835;4065.2276067547073;4068.9796551017353;3612.06754386402;3619.366610843092;3621;3622.366085934977;3625.220577575834;5022.89954200914;5048.343715317022;5049.141645920203;5071.41678706946;5082.526067440438;4600.723956562617;4603.861884143481;4616.953830461686;4624.838130665411;4637.559431853969;5350.973245133789;5361.463853614647;5375.354957405098;5394.811181608625;5407.405185429599;3833.9616603833883;3925.2934471795065;3938.822752976123;3939.625735555118;3950.545687245975;5654.773809047633;5670.858228544278;5676.1769543416385;5679.829605111853;5688.317401911797;4039.1517781265675;4045.8583949561626;4053.61490658387;4064.4106604542176;4082.4284600156;4001.7598944063448;4081.4081958115908;4094.6314831665295;4096.303628383182;4105.885468983476;4619.376384188092;4625.722456652612;4627.282771170474;4628.74541900192;4631.143238500888;5437.401885792559;5476.644018138808;5482.643628164156;5493.890122197614;5500.28496295479;1339.7521458530202;1374.1892283552552;1385.9307034648227;1406.1773862290675;1408.1621435246013;934.8737920380797;948.1087777489138;966.9806603867884;971.5190980464715;976.9364991275544;5175.922361164586;5211;5222.817201397933;5224.686518808883;5247.921281180786;5471.9179212311055;5474.562035037187;5497.092979658311;5521.47545983139;5527.115661494222;5058.165402708585;5187.054854429589;5234.712090834964;5247.553957913544;5343;2955.2907302247304;2977.7320041196394;2980.1208643450236;2988.372441787249;2990.4168687105807;5733.31200255972;5744.080947048452;5748.419184724151;5754.88490230193;5766.596338256366;5576.832695019156;5723.627964182315;5733.913991290134;5742.569307301969;5758.107493338539;5724.971375143181;5746.971265143731;5749.425057494217;5755.9424405756245;5781.739821708034;5378.193271009223;5586.692731899784;5606.27118474612;5619.213310136666;5656.8020119295625;5528.861778455571;5545.861353466034;5548.778048878;5559.805406810904;5564.527015203753;5762.423757624204;5768.826935191783;5769.740361683818;5775.393583673822;5781.392953739965;3966.2067586483395;4056.1684854605323;4066.2070896176147;4076.184763047343;4079.082206503513;5350.919736203882;5586.273784408001;5606.915896261477;5616.522595579421;5620.185073164286;5343.7862485500145;5476.945230547643;5477;5481.917771233514;5491.462390530658;5463.235147079333;5464.91802622953;5465.398806131402;5465.562754979672;5472.248880358247;5231.39838918514;5464.808731694372;5467.699276539828;5473.2063850742215;5507.7246137693755;5488.286522752017;5527.64070335443;5532.529734972572;5533.861653458534;5544.556435485232;5290.867728306823;5329.307190065267;5335.439778823167;5338.172583249602;5340.759665815125 05:43:22 INFO - PROCESS | 31369 | |5;yui.html;699.6082193971375;844.9831003379934;845.7716416567523;848.1094850407071;849.4436144325465;15774.84225157748;16393.83606163938;16956.4065257716;17119.74320385195;17124.143792810373;16644.83355166448;16763.83236167638;16765.413210537634;16787.580310492212;16792.748108778378;672.9158855143107;811.3549727966281;830.9875351869727;833.8290650416681;833.9666413343459;798.648594618366;817.8159914019358;833.0836080311669;835.3192148399065;835.6448509383488;877.3814460805118;878.9121087891192;879.4371602174614;879.9032106468284;880.0891077734547;705.1432509500981;845.488479469918;866.653338664536;870.9390342675983;872.4111224923183;627.2379059442799;636.5893998371071;636.6912047656883;636.7834936121741;637.2512298049819;16107.033577985241;16118.274677639534;16127;16143.596410089724;16146.919265403716;14374.137551746928;14635.92682036583;14669.70660586793;14927.40290388383;14975.1014939104;13529.458821647122;13678.658033549142;13923.164610123426;13958.441662333495;14103.78844317336;4454.844080457168;4556.031110956111;4556.430446194226;4729.952700472985;4730.62155027597;85356.57321713351;86730.5663471685;87406;88982.5550872248;90562;11558.190926635054;11699.356535390636;11836.644900652995;11866.718050573561;12128.878711212952;22308.665370019462;22343.106275749113;22370.776292237195;22415.663765043537;22508.099676012775;8450.704225352207;8454.788630284293;8457.534835584102;8540.957295213484;8620;4505.7521836298665;4588.334691469785;4624.1676498230645;4678.508756580578;4724.740139292372;3365.983170084183;3495.965040349615;3501.6148223695373;3502.562179727534;3508.8771892983623;588.4406323243439;593.5370411079365;600.579594284003;604.1451346344872;616.5283558078074;676.9221539523018;707.5012116457884;759.5063208914139;768.5503980171662;778.1401551285747;9596.08078384312;9985.301028927905;10031.634865999471;10202.234832387601;10792.838107428395;718.9636595647456;743.0013264319073;773.8220209351881;780.0911937592641;786.4101923557332;4330.783460826946;4793.832215872428;4883.790523690773;4944.964432796705;5013.523715247045;109386;111006;112262.38688065564;112947;114909;87155.56422217848;98389.50805245928;100868.49565752124;101140.49429752951;101220;88267.55866220756;90949;91559.85683149696;91986.54006730058;94279.0572094284;8919.955400223216;9029.77425564366;9202.825228657046;9243.306751993761;9351;48246.276305854735;49797.75101124616;51143.488565113876;51158.488415115375;51454;69271.65364173146;71844;76536.23463765292;79101.60449197948;79334.20665793268;12368.938155309166;12462.376881155724;12661.81007284873;12688.17526860751;12819.27374719162;13451.596452106452;13696.209944367458;13889.65275868071;14208.218547979961;15344.923275383551;12574.937125314314;12672.303023333805;12741.49034038628;13182.868171318163;13877.097988630707;13081.888039516747;13222.603321900358;13485.865141348853;13591.864081359456;13643.79534307006;12505.249685018927;12729.6181114563;12932.288724120264;13267.933660331637;13286.402111904794;10054.330298444458;10135.061160709736;10357.482125893826;10411.895881041093;10765.89234107649;10338.534765935401;10606.628767993083;10619.522121504397;10658.413787241776;10679.19906007037 05:43:22 INFO - PROCESS | 31369 | __end_tp_report 05:43:22 INFO - PROCESS | 31369 | __start_cc_report 05:43:22 INFO - PROCESS | 31369 | _x_x_mozilla_cycle_collect,986 05:43:22 INFO - PROCESS | 31369 | __end_cc_report 05:43:22 INFO - PROCESS | 31369 | __startTimestamp1472561002940__endTimestamp 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | ------- Summary: start ------- 05:43:22 INFO - PROCESS | 31369 | Number of tests: 6 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#0] dojo.html Cycles:165 Average:5293.28 Median:5493.37 stddev:602.09 (11.0%) stddev-sans-first:575.87 05:43:22 INFO - PROCESS | 31369 | Values: 2977.2 2987.8 3020.4 3035.2 3069.8 5131.6 5215.4 5254.4 5296.0 5335.2 5252.8 5318.5 5336.3 5342.8 5387.0 4737.5 4801.8 4810.0 4826.4 4993.0 4894.0 4906.0 4943.1 4950.4 4981.3 4778.0 4814.6 4885.1 4892.6 4896.5 4808.4 4815.1 4826.9 4856.6 4951.8 4817.2 4851.4 4856.5 4870.0 4880.8 5234.2 5246.6 5340.0 5383.6 5420.9 5641.1 5674.4 5683.1 5692.0 5748.3 5709.9 5711.0 5727.5 5730.6 5732.7 5059.8 5284.9 5308.4 5334.8 5347.8 5900.2 5900.9 5903.1 5908.1 5957.4 5632.2 5642.9 5650.0 5650.6 5664.4 5521.1 5626.1 5648.2 5650.9 5734.4 5712.5 5714.4 5720.4 5728.4 5768.8 5633.9 5684.7 5687.9 5694.1 5723.7 4253.6 4347.5 4349.9 4388.5 4396.6 3914.4 3923.6 3946.9 3953.8 3959.8 5274.3 5277.8 5278.4 5284.2 5327.4 5669.0 5712.2 5712.9 5715.6 5744.6 5385.8 5440.2 5451.5 5479.8 5493.4 5000.9 5005.2 5009.6 5011.6 5062.6 5672.5 5769.2 5826.7 5925.9 5929.8 5711.6 5714.5 5717.5 5771.6 5780.8 5854.5 5859.7 5869.6 5871.5 5871.5 5402.4 5549.2 5577.6 5584.1 5601.7 5743.2 5746.7 5747.1 5788.2 5811.5 5610.5 5621.6 5621.9 5623.0 5623.2 5440.5 5457.9 5467.5 5610.9 5619.4 5502.5 5507.4 5511.7 5561.7 5572.2 5507.2 5553.6 5555.1 5566.3 5570.0 5303.6 5421.3 5435.6 5461.6 5518.9 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#1] ext.html Cycles:180 Average:56645.68 Median:40914.90 stddev:112348.77 (274.6%) stddev-sans-first:112585.00 05:43:22 INFO - PROCESS | 31369 | Values: 552.9 581.8 597.8 609.4 609.5 31564.7 32540.5 34491.0 42967.6 43730.6 45816.8 46443.0 46448.0 46510.8 46542.5 8790.8 8943.5 8948.3 8950.4 8951.1 8862.0 8949.6 8956.0 8956.3 8957.5 8883.1 8914.6 8936.3 8956.1 8986.1 8881.5 8925.6 8960.4 8963.8 8969.4 8825.0 8884.1 8889.1 8891.0 8896.4 42419.4 42441.8 42796.8 42798.1 42812.0 3667.9 3693.4 3736.5 3817.9 4034.7 31961.0 32084.5 32126.7 32379.7 32728.3 4274.4 4389.8 4414.2 4418.7 4512.7 252875.0 253021.0 254626.0 254799.0 254857.3 57622.0 58087.0 58156.4 58237.0 58262.0 52474.5 58253.4 59490.7 59822.1 61566.0 14896.3 14901.2 14915.5 14925.5 14932.9 12758.7 12838.6 12839.3 12862.4 12867.4 15116.8 16821.8 17295.7 17403.5 17822.4 5981.9 6003.1 6037.1 6053.5 6057.2 972.4 1019.3 1031.6 1039.3 1063.8 30756.7 31804.4 31839.8 32009.5 32426.0 1055.4 1059.0 1062.2 1099.5 1124.4 15078.8 16398.8 16501.7 16575.4 16758.2 563817.0 563820.0 563830.0 564510.0 567846.0 56963.7 56980.4 57012.7 57037.0 57133.7 1241.6 1363.6 1365.7 1368.9 1398.7 30448.5 31809.0 32580.0 32964.0 33575.0 48462.5 49078.8 49102.0 49111.8 49118.5 387120.0 389146.0 389182.0 389466.0 390103.0 24887.9 25332.4 25619.7 25693.1 29225.6 48041.0 48377.8 48832.0 48863.8 50056.2 40080.4 40834.4 40915.8 41152.0 42576.0 40598.6 40769.4 40914.0 42614.0 42887.8 39595.4 40196.4 40241.8 40603.0 41120.4 35235.0 36928.4 37114.6 37255.3 37386.0 27868.7 27875.2 28115.2 28354.9 28404.2 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#2] jquery.html Cycles:180 Average:27723.75 Median:2453.85 stddev:73923.12 (3012.5%) stddev-sans-first:74101.99 05:43:22 INFO - PROCESS | 31369 | Values: 382.9 395.3 398.2 404.6 421.9 15951.9 18925.7 19269.9 19983.4 20020.0 19492.7 19677.3 19770.8 19937.4 20275.3 5305.0 5763.0 5943.3 5984.1 5999.6 5889.9 5893.9 5910.4 5944.5 5952.0 5670.4 5834.0 5871.0 5872.2 5904.1 5672.3 5861.1 5877.7 5897.7 5906.5 5218.8 5255.8 5257.9 5260.1 5260.9 23664.0 23731.3 23765.3 23836.4 23849.6 2259.2 2316.2 2382.4 2447.9 2459.8 10404.4 10511.2 10586.2 10809.5 11089.8 1960.6 1976.7 1987.0 1996.7 2021.5 57483.4 72849.0 74640.0 85169.1 88372.0 9515.9 9865.9 10087.6 10096.1 11093.6 13583.8 13584.2 13646.8 13648.5 13653.8 7776.9 7909.8 7943.9 8047.8 8140.4 7441.9 7677.8 7779.5 7990.9 8254.8 4150.9 4176.4 4215.0 4340.2 4396.3 1993.6 2020.1 2025.4 2034.6 2035.9 853.9 924.3 999.0 1017.9 1030.9 19460.6 20157.6 20184.1 20201.9 20279.4 985.6 987.8 997.7 1001.2 1020.3 10544.7 11241.3 11261.5 11448.3 11497.8 438467.0 442259.0 445768.0 451799.0 454120.0 95330.5 97830.0 98022.5 99209.0 100850.5 980.6 1005.1 1044.4 1064.6 1070.6 17255.8 17519.2 17934.0 18013.4 18278.6 29272.9 39016.0 40239.0 40905.8 44023.6 51695.5 52644.2 52908.0 59086.7 61199.0 8528.8 8730.7 8752.3 8904.7 9127.8 13046.5 13648.0 13695.0 13881.2 13896.8 11941.0 11976.9 12039.2 12040.7 12091.4 11766.4 11906.4 11966.8 12434.0 12563.3 11734.1 11794.2 11867.2 11907.3 12463.9 8683.5 8698.6 8734.7 8879.4 8956.6 10185.2 11520.9 12241.5 12250.4 12300.6 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#3] mootools.html Cycles:180 Average:5907.35 Median:4772.13 stddev:3352.32 (70.2%) stddev-sans-first:3343.82 05:43:22 INFO - PROCESS | 31369 | Values: 1297.3 1443.3 1463.5 1575.4 1782.6 8392.9 8874.6 8888.2 9122.5 9132.3 8938.3 9039.0 9070.1 9071.0 9074.9 2089.7 2298.2 2411.2 2435.6 2440.4 2418.8 2456.9 2459.4 2480.9 2491.0 2429.4 2434.4 2447.2 2447.4 2448.8 2331.7 2359.4 2373.4 2412.3 2420.1 2414.0 2425.0 2427.8 2430.7 2432.0 8758.0 8921.0 8941.0 8966.3 8967.3 5266.8 5386.8 5397.8 5399.9 5412.6 3239.9 3361.6 3394.1 3396.6 3409.6 1051.1 1086.7 1087.1 1090.9 1095.5 12081.8 12362.2 12443.1 12528.0 12542.9 4491.5 4582.3 4767.4 4776.9 4781.5 10590.3 10591.0 10599.9 10603.8 10607.8 5322.1 5340.3 5343.3 5345.6 5346.2 4309.5 4824.3 4870.0 4911.5 4956.6 4104.3 4106.4 4226.4 4254.7 4298.0 1303.6 1306.0 1316.4 1350.6 1375.3 986.4 998.3 1008.6 1008.7 1018.8 6028.7 6103.4 6121.3 6194.1 6379.9 480.7 487.6 491.0 506.8 510.8 4207.2 4220.1 4247.1 4321.6 4383.6 10619.9 11049.4 11105.1 11115.5 11118.7 11033.8 11034.1 11046.3 11051.6 11056.4 7797.0 7940.0 8155.6 8246.1 8336.8 5984.8 6304.8 6305.6 6354.7 6395.1 9732.8 10219.1 10245.1 10288.7 10328.7 10100.0 10308.1 10354.7 10450.1 10450.1 6826.7 7607.1 7737.3 7829.1 7926.2 8642.3 8843.7 8845.6 8854.2 8856.2 7744.0 7763.7 7889.6 7987.0 8087.2 7698.5 7821.2 7871.5 7903.2 7917.0 7208.6 7342.3 7346.5 7349.4 7355.4 5200.2 5300.3 5364.8 5503.0 5555.2 5415.6 5431.3 5439.6 5513.2 5539.6 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#4] prototype.html Cycles:180 Average:4607.20 Median:5250.03 stddev:1341.46 (25.6%) stddev-sans-first:1306.40 05:43:22 INFO - PROCESS | 31369 | Values: 338.5 345.1 348.8 349.7 352.9 5155.5 5183.6 5333.4 5366.2 5377.4 5157.4 5181.4 5187.5 5242.1 5252.1 4157.6 4162.3 4164.2 4167.0 4191.4 3935.5 4043.7 4167.4 4173.9 4215.3 4021.5 4039.8 4044.4 4048.8 4049.7 4030.6 4049.7 4055.1 4065.2 4069.0 3612.1 3619.4 3621.0 3622.4 3625.2 5022.9 5048.3 5049.1 5071.4 5082.5 4600.7 4603.9 4617.0 4624.8 4637.6 5351.0 5361.5 5375.4 5394.8 5407.4 3834.0 3925.3 3938.8 3939.6 3950.5 5654.8 5670.9 5676.2 5679.8 5688.3 4039.2 4045.9 4053.6 4064.4 4082.4 4001.8 4081.4 4094.6 4096.3 4105.9 4619.4 4625.7 4627.3 4628.7 4631.1 5437.4 5476.6 5482.6 5493.9 5500.3 1339.8 1374.2 1385.9 1406.2 1408.2 934.9 948.1 967.0 971.5 976.9 5175.9 5211.0 5222.8 5224.7 5247.9 5471.9 5474.6 5497.1 5521.5 5527.1 5058.2 5187.1 5234.7 5247.6 5343.0 2955.3 2977.7 2980.1 2988.4 2990.4 5733.3 5744.1 5748.4 5754.9 5766.6 5576.8 5723.6 5733.9 5742.6 5758.1 5725.0 5747.0 5749.4 5755.9 5781.7 5378.2 5586.7 5606.3 5619.2 5656.8 5528.9 5545.9 5548.8 5559.8 5564.5 5762.4 5768.8 5769.7 5775.4 5781.4 3966.2 4056.2 4066.2 4076.2 4079.1 5350.9 5586.3 5606.9 5616.5 5620.2 5343.8 5476.9 5477.0 5481.9 5491.5 5463.2 5464.9 5465.4 5465.6 5472.2 5231.4 5464.8 5467.7 5473.2 5507.7 5488.3 5527.6 5532.5 5533.9 5544.6 5290.9 5329.3 5335.4 5338.2 5340.8 05:43:22 INFO - PROCESS | 31369 | 05:43:22 INFO - PROCESS | 31369 | [#5] yui.html Cycles:180 Average:21281.29 Median:4572.38 stddev:30562.57 (668.4%) stddev-sans-first:30609.23 05:43:22 INFO - PROCESS | 31369 | Values: 699.6 845.0 845.8 848.1 849.4 15774.8 16393.8 16956.4 17119.7 17124.1 16644.8 16763.8 16765.4 16787.6 16792.7 672.9 811.4 831.0 833.8 834.0 798.6 817.8 833.1 835.3 835.6 877.4 878.9 879.4 879.9 880.1 705.1 845.5 866.7 870.9 872.4 627.2 636.6 636.7 636.8 637.3 16107.0 16118.3 16127.0 16143.6 16146.9 14374.1 14635.9 14669.7 14927.4 14975.1 13529.5 13678.7 13923.2 13958.4 14103.8 4454.8 4556.0 4556.4 4730.0 4730.6 85356.6 86730.6 87406.0 88982.6 90562.0 11558.2 11699.4 11836.6 11866.7 12128.9 22308.7 22343.1 22370.8 22415.7 22508.1 8450.7 8454.8 8457.5 8541.0 8620.0 4505.8 4588.3 4624.2 4678.5 4724.7 3366.0 3496.0 3501.6 3502.6 3508.9 588.4 593.5 600.6 604.1 616.5 676.9 707.5 759.5 768.6 778.1 9596.1 9985.3 10031.6 10202.2 10792.8 719.0 743.0 773.8 780.1 786.4 4330.8 4793.8 4883.8 4945.0 5013.5 109386.0 111006.0 112262.4 112947.0 114909.0 87155.6 98389.5 100868.5 101140.5 101220.0 88267.6 90949.0 91559.9 91986.5 94279.1 8920.0 9029.8 9202.8 9243.3 9351.0 48246.3 49797.8 51143.5 51158.5 51454.0 69271.7 71844.0 76536.2 79101.6 79334.2 12368.9 12462.4 12661.8 12688.2 12819.3 13451.6 13696.2 13889.7 14208.2 15344.9 12574.9 12672.3 12741.5 13182.9 13877.1 13081.9 13222.6 13485.9 13591.9 13643.8 12505.2 12729.6 12932.3 13267.9 13286.4 10054.3 10135.1 10357.5 10411.9 10765.9 10338.5 10606.6 10619.5 10658.4 10679.2 05:43:22 INFO - PROCESS | 31369 | -------- Summary: end -------- 05:43:22 INFO - PROCESS | 31369 | 05:43:23 INFO - TEST-INFO | 31369: exit 0 05:43:24 INFO - TEST-OK | dromaeo_css | took 1116791ms 05:43:24 INFO - TEST-START | kraken 05:43:24 INFO - operating with platform_type : linux_ 05:43:24 INFO - Initialising browser for kraken test... 05:43:24 INFO - TEST-INFO | started process 31678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNv_lgt/profile http://localhost:43560/getInfo.html) 05:43:28 INFO - PROCESS | 31678 | __metrics Screen width/height:1600/1200 05:43:28 INFO - PROCESS | 31678 | colorDepth:24 05:43:28 INFO - PROCESS | 31678 | Browser inner width/height: 1024/697 05:43:28 INFO - PROCESS | 31678 | __metrics 05:43:29 INFO - PROCESS | 31678 | 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" 05:43:29 INFO - PROCESS | 31678 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:29 INFO - PROCESS | 31678 | console.error: PushService: 05:43:29 INFO - PROCESS | 31678 | stateChangeProcessEnqueue: Error transitioning state 05:43:29 INFO - PROCESS | 31678 | UnknownError 05:43:30 INFO - TEST-INFO | 31678: exit 0 05:43:30 INFO - Browser initialized. 05:43:30 INFO - Running cycle 1/1 for kraken test... 05:43:30 INFO - TEST-INFO | started process 31758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNv_lgt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:42 INFO - PROCESS | 31758 | RSS: Main: 184553472 05:43:42 INFO - PROCESS | 31758 | 05:43:45 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43560/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:45 INFO - PROCESS | 31758 | RSS: Main: 238186496 05:43:45 INFO - PROCESS | 31758 | 05:43:48 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43560/tests/kraken/driver.html?testName=audio-dft) 05:43:49 INFO - PROCESS | 31758 | RSS: Main: 229396480 05:43:49 INFO - PROCESS | 31758 | 05:43:52 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43560/tests/kraken/driver.html?testName=audio-fft) 05:43:52 INFO - PROCESS | 31758 | RSS: Main: 306688000 05:43:52 INFO - PROCESS | 31758 | 05:43:55 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43560/tests/kraken/driver.html?testName=audio-oscillator) 05:43:55 INFO - PROCESS | 31758 | RSS: Main: 216555520 05:43:55 INFO - PROCESS | 31758 | 05:43:57 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43560/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:58 INFO - PROCESS | 31758 | RSS: Main: 218222592 05:43:58 INFO - PROCESS | 31758 | 05:44:01 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43560/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:01 INFO - PROCESS | 31758 | RSS: Main: 257306624 05:44:01 INFO - PROCESS | 31758 | 05:44:05 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43560/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:05 INFO - PROCESS | 31758 | RSS: Main: 255635456 05:44:05 INFO - PROCESS | 31758 | 05:44:08 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43560/tests/kraken/driver.html?testName=json-parse-financial) 05:44:09 INFO - PROCESS | 31758 | RSS: Main: 255401984 05:44:09 INFO - PROCESS | 31758 | 05:44:10 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43560/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:11 INFO - PROCESS | 31758 | RSS: Main: 218157056 05:44:11 INFO - PROCESS | 31758 | 05:44:13 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:13 INFO - PROCESS | 31758 | RSS: Main: 225456128 05:44:13 INFO - PROCESS | 31758 | 05:44:15 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:16 INFO - PROCESS | 31758 | RSS: Main: 223457280 05:44:16 INFO - PROCESS | 31758 | 05:44:19 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:19 INFO - PROCESS | 31758 | RSS: Main: 223756288 05:44:19 INFO - PROCESS | 31758 | 05:44:22 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:23 INFO - PROCESS | 31758 | RSS: Main: 222031872 05:44:23 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | Cycle 1(1): loaded http://localhost:43560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43560/tests/kraken/driver.html?testName=ai-astar) 05:44:24 INFO - PROCESS | 31758 | RSS: Main: 272785408 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | __start_tp_report 05:44:24 INFO - PROCESS | 31758 | _x_x_mozilla_page_load 05:44:24 INFO - PROCESS | 31758 | _x_x_mozilla_page_load_details 05:44:24 INFO - PROCESS | 31758 | |i|pagename|runs| 05:44:24 INFO - PROCESS | 31758 | |0;ai-astar;112;112;131;111;113;111;109;111;111;111 05:44:24 INFO - PROCESS | 31758 | |1;audio-beat-detection;147;117;144;176;118;148;148;118;190;116 05:44:24 INFO - PROCESS | 31758 | |2;audio-dft;161;161;161;160;160;161;162;166;160;163 05:44:24 INFO - PROCESS | 31758 | |3;audio-fft;75;76;74;173;75;75;77;77;168;74 05:44:24 INFO - PROCESS | 31758 | |4;audio-oscillator;82;82;155;82;82;81;152;82;82;138 05:44:24 INFO - PROCESS | 31758 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 05:44:24 INFO - PROCESS | 31758 | |6;imaging-darkroom;146;146;146;148;146;146;146;147;146;146 05:44:24 INFO - PROCESS | 31758 | |7;imaging-desaturate;96;95;95;95;95;95;95;94;96;95 05:44:24 INFO - PROCESS | 31758 | |8;json-parse-financial;52;51;52;52;52;52;53;54;54;52 05:44:24 INFO - PROCESS | 31758 | |9;json-stringify-tinderbox;61;62;62;62;62;62;61;62;62;62 05:44:24 INFO - PROCESS | 31758 | |10;stanford-crypto-aes;88;83;80;94;89;79;79;79;88;79 05:44:24 INFO - PROCESS | 31758 | |11;stanford-crypto-ccm;124;123;119;135;122;139;123;122;162;136 05:44:24 INFO - PROCESS | 31758 | |12;stanford-crypto-pbkdf2;171;166;165;167;165;164;164;164;164;167 05:44:24 INFO - PROCESS | 31758 | |13;stanford-crypto-sha256-iterative;65;69;66;65;66;65;68;65;63;64 05:44:24 INFO - PROCESS | 31758 | __end_tp_report 05:44:24 INFO - PROCESS | 31758 | __start_cc_report 05:44:24 INFO - PROCESS | 31758 | _x_x_mozilla_cycle_collect,1707 05:44:24 INFO - PROCESS | 31758 | __end_cc_report 05:44:24 INFO - PROCESS | 31758 | __startTimestamp1472561064904__endTimestamp 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | ------- Summary: start ------- 05:44:24 INFO - PROCESS | 31758 | Number of tests: 14 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#0] ai-astar Cycles:10 Average:113.20 Median:111.50 stddev:6.34 (5.7%) stddev-sans-first:6.71 05:44:24 INFO - PROCESS | 31758 | Values: 112.0 112.0 131.0 111.0 113.0 111.0 109.0 111.0 111.0 111.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:147.50 stddev:25.85 (17.5%) stddev-sans-first:27.36 05:44:24 INFO - PROCESS | 31758 | Values: 147.0 117.0 144.0 176.0 118.0 148.0 148.0 118.0 190.0 116.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:1.84 (1.1%) stddev-sans-first:1.94 05:44:24 INFO - PROCESS | 31758 | Values: 161.0 161.0 161.0 160.0 160.0 161.0 162.0 166.0 160.0 163.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#3] audio-fft Cycles:10 Average:94.40 Median:75.00 stddev:40.14 (53.5%) stddev-sans-first:41.96 05:44:24 INFO - PROCESS | 31758 | Values: 75.0 76.0 74.0 173.0 75.0 75.0 77.0 77.0 168.0 74.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:82.00 stddev:32.40 (39.5%) stddev-sans-first:33.56 05:44:24 INFO - PROCESS | 31758 | Values: 82.0 82.0 155.0 82.0 82.0 81.0 152.0 82.0 82.0 138.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:44:24 INFO - PROCESS | 31758 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 05:44:24 INFO - PROCESS | 31758 | Values: 146.0 146.0 146.0 148.0 146.0 146.0 146.0 147.0 146.0 146.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 05:44:24 INFO - PROCESS | 31758 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 05:44:24 INFO - PROCESS | 31758 | Values: 52.0 51.0 52.0 52.0 52.0 52.0 53.0 54.0 54.0 52.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 05:44:24 INFO - PROCESS | 31758 | Values: 61.0 62.0 62.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:85.50 stddev:5.51 (6.4%) stddev-sans-first:5.63 05:44:24 INFO - PROCESS | 31758 | Values: 88.0 83.0 80.0 94.0 89.0 79.0 79.0 79.0 88.0 79.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:129.50 stddev:13.11 (10.1%) stddev-sans-first:13.69 05:44:24 INFO - PROCESS | 31758 | Values: 124.0 123.0 119.0 135.0 122.0 139.0 123.0 122.0 162.0 136.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:165.50 stddev:2.21 (1.3%) stddev-sans-first:1.27 05:44:24 INFO - PROCESS | 31758 | Values: 171.0 166.0 165.0 167.0 165.0 164.0 164.0 164.0 164.0 167.0 05:44:24 INFO - PROCESS | 31758 | 05:44:24 INFO - PROCESS | 31758 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.78 (2.7%) stddev-sans-first:1.87 05:44:24 INFO - PROCESS | 31758 | Values: 65.0 69.0 66.0 65.0 66.0 65.0 68.0 65.0 63.0 64.0 05:44:24 INFO - PROCESS | 31758 | -------- Summary: end -------- 05:44:24 INFO - PROCESS | 31758 | 05:44:26 INFO - TEST-INFO | 31758: exit 0 05:44:26 INFO - TEST-OK | kraken | took 62128ms 05:44:26 INFO - SUITE-END | took 1178s 05:44:26 INFO - Completed test suite (00:19:39) 05:44:26 INFO - javascript benchmark 05:44:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2977.225921260472, 2987.835669038203, 3020.3959208158376, 3035.2108451802524, 3069.785115041948, 5131.589472842173, 5215.42630310666, 5254.369475662926, 5296.0, 5335.199720041991, 5252.763625636846, 5318.494742999409, 5336.30628018357, 5342.839714808562, 5387.003404370194, 4737.478877323491, 4801.783919723621, 4809.975950120244, 4826.372571565692, 4992.975035124819, 4894.0, 4905.975470122645, 4943.060818444502, 4950.430700469438, 4981.277714731362, 4777.952220477803, 4814.566688997972, 4885.071836351082, 4892.608591312686, 4896.46138924718, 4808.447028591721, 4815.109204797123, 4826.85519434414, 4856.635752318555, 4951.826686066006, 4817.229243321087, 4851.417829860439, 4856.538628830255, 4870.001649661846, 4880.829170978998, 5234.214867769827, 5246.580273578143, 5340.0, 5383.623146379755, 5420.91868621971, 5641.097424412115, 5674.404187560288, 5683.119116536902, 5691.971540142315, 5748.281464816898, 5709.942900570983, 5711.0, 5727.541796656299, 5730.598858079936, 5732.742026608813, 5059.797608095671, 5284.947150528484, 5308.442613525563, 5334.759935802898, 5347.786088556492, 5900.1739756434135, 5900.940990590125, 5903.114532820111, 5908.084246941731, 5957.4042595740075, 5632.183333416631, 5642.943570564242, 5650.0, 5650.63270887391, 5664.405237450009, 5521.1442226454965, 5626.099824028136, 5648.237487939075, 5650.915236271459, 5734.39788822176, 5712.514436272881, 5714.371419143891, 5720.370759216483, 5728.369879313273, 5768.826935191951, 5633.915491267634, 5684.658920464785, 5687.886242275132, 5694.088945768657, 5723.7424315905055, 4253.63844073251, 4347.5217726050105, 4349.9130017399475, 4388.451443569554, 4396.560343965642, 3914.432407300925, 3923.588023257517, 3946.881593552198, 3953.78254196022, 3959.762414255154, 5274.26160337553, 5277.788888444496, 5278.445763194886, 5284.180951952377, 5327.38735045467, 5669.044179757977, 5712.200291959129, 5712.942870571324, 5715.571332150105, 5744.569157313218, 5385.811496597678, 5440.211169380417, 5451.48210919962, 5479.75341109651, 5493.368262649847, 5000.924986125138, 5005.199168133081, 5009.574186194214, 5011.624128190328, 5062.569681577107, 5672.4894759471845, 5769.1634712967225, 5826.6795326256615, 5925.851853703692, 5929.792457263975, 5711.571632127524, 5714.457126572969, 5717.456841600041, 5771.624844385269, 5780.768769249183, 5854.4730974210825, 5859.677717725566, 5869.647821130746, 5871.500922421642, 5871.500922421642, 5402.405735369185, 5549.1676248563035, 5577.637453565505, 5584.134459158837, 5601.691906944994, 5743.167240749985, 5746.683932383592, 5747.1379293106365, 5788.189653448605, 5811.53507719373, 5610.4670056344585, 5621.578381621476, 5621.887562248814, 5623.0, 5623.156526521055, 5440.510354068153, 5457.945420545744, 5467.507924286833, 5610.887782244414, 5619.353774315842, 5502.504774570308, 5507.366652834975, 5511.669299842022, 5561.6663000220115, 5572.164175373727, 5507.201455788808, 5553.639013464273, 5555.0834112371, 5566.304211973504, 5570.025245582088, 5303.5757139429525, 5421.322334708161, 5435.646682965595, 5461.644993075437, 5518.862028449321], "value": 5252.112921859129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.8858822458473, 581.8138195777351, 597.8087012156109, 609.3875654966757, 609.5032548472997, 31564.684353156463, 32540.511892321632, 34490.99999999997, 42967.57032429675, 43730.562694373046, 45816.770916145375, 46443.0, 46448.0, 46510.767446162805, 46542.534574654244, 8790.824183516326, 8943.50810705411, 8948.328875334377, 8950.41822281553, 8951.060138685441, 8861.955690221539, 8949.64201431942, 8955.955220223923, 8956.283497320199, 8957.507337096456, 8883.067277935821, 8914.598843052077, 8936.285097192242, 8956.059613740561, 8986.14631609996, 8881.511516866602, 8925.553722313858, 8960.372773905829, 8963.775905602412, 8969.416987895831, 8824.955875220583, 8884.11158884113, 8889.111088891059, 8890.955545222298, 8896.421732587361, 42419.363709544385, 42441.78779106116, 42796.786016069724, 42798.1440371194, 42812.0, 3667.905824039653, 3693.3905905525558, 3736.532933383327, 3817.938313310809, 4034.7377420467574, 31961.0, 32084.518732219036, 32126.678733212837, 32379.6762032379, 32728.345433091443, 4274.444322238121, 4389.824407023715, 4414.161309351213, 4418.668599855024, 4512.684112112153, 252875.0, 253021.0, 254626.0, 254799.0, 254857.31997520363, 57622.0, 58087.0, 58156.41843581595, 58237.0, 58262.0, 52474.47525524772, 58253.417465825645, 59490.70254648699, 59822.10266846001, 61566.0, 14896.255187240595, 14901.180435076174, 14915.477958271409, 14925.477608283658, 14932.850671493363, 12758.68103297425, 12838.55065072718, 12839.293838838954, 12862.421191046426, 12867.356632168354, 15116.848831511765, 16821.83178168227, 17295.74056389155, 17403.47789566315, 17822.37621683235, 5981.910271345933, 6003.099535069775, 6037.094435834572, 6053.4551890330085, 6057.182280392179, 972.3825370889537, 1019.2508506247901, 1031.6389263757624, 1039.2569312941284, 1063.7978784031009, 30756.69243307583, 31804.363912721616, 31839.840800795846, 32009.51985720216, 32426.0, 1055.4089709762557, 1058.957641694339, 1062.235190662737, 1099.483242875847, 1124.3647339253296, 15078.849211507964, 16398.83601163973, 16501.669966600602, 16575.41986030483, 16758.24587893548, 563817.0, 563820.0, 563830.0, 564510.0, 567846.0, 56963.715181423824, 56980.43019569751, 57012.71493642505, 57037.0, 57133.71433142808, 1241.6026871401066, 1363.5704753002772, 1365.6790654196257, 1368.9452421903013, 1398.7342404943029, 30448.543271851384, 31809.0, 32580.0, 32964.0, 33575.0, 48462.51537484721, 49078.75460622674, 49102.0, 49111.75444122757, 49118.508814911394, 387120.0, 389146.0, 389182.0, 389466.0, 390103.0, 24887.87556062208, 25332.366690832878, 25619.743802561738, 25693.100741473958, 29225.56161657534, 48041.0, 48377.758111209216, 48832.0, 48863.755681221366, 50056.249156261954, 40080.3987940187, 40834.387484187166, 40915.7954210227, 41152.0, 42576.0, 40598.59401406066, 40769.38845917373, 40914.0, 42614.0, 42887.78556107199, 39595.406068908414, 40196.39705404363, 40241.79879100586, 40603.0, 41120.38319425151, 35235.0, 36928.44607330946, 37114.62885371112, 37255.25489490249, 37386.0, 27868.721312786613, 27875.16374508768, 28115.23603222326, 28354.85822570874, 28404.234072298947], "value": 19559.011336051506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.91193025604093, 395.2805893274242, 398.2115411485259, 404.58327922240096, 421.9472565929259, 15951.920240398797, 18925.716114258295, 19269.903650481745, 19983.400497985047, 20020.0, 19492.70760938587, 19677.311294104707, 19770.802291977077, 19937.401877943685, 20275.290364837198, 5305.0, 5762.971185144069, 5943.3462319144855, 5984.072468767348, 5999.640021598718, 5889.941100588982, 5893.911591326134, 5910.379410161936, 5944.524438044946, 5952.047672372421, 5670.376258611549, 5833.970830145843, 5870.970645146768, 5872.17789509469, 5904.084866845646, 5672.262605861211, 5861.120831875211, 5877.735501902424, 5897.7346019429015, 5906.468417842393, 5218.76515556797, 5255.789768409259, 5257.894842103136, 5260.053190425722, 5260.947390526084, 23664.0, 23731.288061358013, 23765.287041388787, 23836.40408989772, 23849.642255366183, 2259.1979847154353, 2316.247219653619, 2382.38058104894, 2447.8898449569633, 2459.8155138364514, 10404.427756473391, 10511.211659125596, 10586.15310775144, 10809.459527023697, 11089.83365249522, 1960.6274807786617, 1976.743023406948, 1986.960260794776, 1996.7204591357222, 2021.5249416387137, 57483.425165748224, 72849.0, 74640.0, 85169.1283461901, 88372.0, 9515.857262141073, 9865.901340986498, 10087.596496140219, 10096.141827944706, 11093.55625774976, 13583.796243056362, 13584.184948903097, 13646.795298070538, 13648.522301719393, 13653.795193072112, 7776.883346749803, 7909.802254943673, 7943.880841787378, 8047.798805029804, 8140.389470789596, 7441.85116297671, 7677.808054798675, 7779.533228006338, 7990.880136797953, 8254.793630159294, 4150.896227594334, 4176.394422808737, 4215.028404974486, 4340.153670034313, 4396.252637051709, 1993.5713821528152, 2020.1212472574477, 2025.3721346382374, 2034.603252365804, 2035.94910127248, 853.923146916768, 924.3021518753433, 998.9900100999042, 1017.8574999500075, 1030.9123724483352, 19460.610787784164, 20157.596848062956, 20184.091715872823, 20201.898990504953, 20279.391618251477, 985.556499575194, 987.7876256604865, 997.6757553795046, 1001.234055947201, 1020.3061917895753, 10544.736381590368, 11241.325520468798, 11261.549538018551, 11448.255863368855, 11497.770044599061, 438467.0, 442259.0, 445768.0, 451799.0, 454120.0, 95330.52334738281, 97830.0, 98022.50988745154, 99209.0, 100850.49574752226, 980.6469670918464, 1005.1255407795119, 1044.38903241603, 1064.6433444795925, 1070.5824728355947, 17255.82744172542, 17519.21163547618, 17934.040883254638, 18013.369532066314, 18278.634427311114, 29272.853635732536, 39016.0, 40239.0, 40905.79547102245, 44023.55976440194, 51695.483045169065, 52644.21033684575, 52908.0, 59086.704566476896, 61199.0, 8528.786780330542, 8730.69442569507, 8752.256058234867, 8904.688335908211, 9127.77180570491, 13046.478140874638, 13648.0, 13695.0, 13881.167129972235, 13896.791548126585, 11941.0, 11976.880231197925, 12039.217450866015, 12040.698982525508, 12091.395430228278, 11766.411679415824, 11906.404679765803, 11966.820497692368, 12434.0, 12563.309018004098, 11734.119941004288, 11794.174407791372, 11867.228630139014, 11907.285562866256, 12463.937680311541, 8683.522406267462, 8698.608562614823, 8734.737957861274, 8879.422837515542, 8956.596953137256, 10185.23610729213, 11520.94239528797, 12241.490494448795, 12250.387480626112, 12300.630981070582], "value": 9201.778815795507, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.344840855369, 1443.2999995002426, 1463.4950941925035, 1575.3855996161499, 1782.639724120034, 8392.91607083929, 8874.600642971065, 8888.200061994412, 9122.498262595554, 9132.269418446525, 8938.329625278115, 9039.0, 9070.092990700927, 9071.0, 9074.909250907489, 2089.707440958267, 2298.241580278504, 2411.1801987324307, 2435.6468312094735, 2440.389902524369, 2418.7702168293986, 2456.91400800972, 2459.409741662006, 2480.8635525046116, 2491.040949234549, 2429.356220601542, 2434.4279094412886, 2447.2413551799, 2447.3514518652573, 2448.8040956723507, 2331.7318508371586, 2359.4337359033793, 2373.394784330002, 2412.3124909400735, 2420.1045613123083, 2414.0343862454984, 2425.0421083672027, 2427.7693619106158, 2430.7447717989535, 2432.0, 8758.0, 8920.955395223047, 8940.955295223483, 8966.327525435554, 8967.327450441244, 5266.815661451831, 5386.78452861885, 5397.8380648580605, 5399.892002159974, 5412.648177868426, 3239.87040518379, 3361.5966084069823, 3394.100563350714, 3396.5924089109217, 3409.556757621518, 1051.148569658579, 1086.7065892209139, 1087.0814162033148, 1090.9236353455262, 1095.4522738630685, 12081.818772718329, 12362.19645723034, 12443.128980971245, 12528.0, 12542.937285313606, 4491.528389519053, 4582.312653102062, 4767.380240568773, 4776.880577985578, 4781.474037855833, 10590.258681892194, 10591.0, 10599.947000264949, 10603.787924241473, 10607.787844243227, 5322.0952438085615, 5340.279062326614, 5343.278657381282, 5345.572354211654, 5346.198070289488, 4309.461317335333, 4824.324594556765, 4869.975650121727, 4911.533404326583, 4956.553910148032, 4104.305191425641, 4106.404571337199, 4226.429432026698, 4254.744715317091, 4297.957020429818, 1303.5763376902544, 1305.9934700326628, 1316.380402998046, 1350.59482155353, 1375.2573610250354, 986.363795351994, 998.256299057207, 1008.5612758450097, 1008.6570566007446, 1018.765683892694, 6028.728707208098, 6103.42017508336, 6121.296050954108, 6194.101855230966, 6379.872402551923, 480.7211817146047, 487.55875932281344, 491.04981860100105, 506.8378119001884, 510.83653230965734, 4207.242696314632, 4220.092680073799, 4247.110036033997, 4321.5678432157165, 4383.561643835591, 10619.94690026545, 11049.447527623586, 11105.05607023412, 11115.499802508744, 11118.666440006813, 11033.83449248262, 11034.117270618332, 11046.33721976668, 11051.613193538187, 11056.447177641086, 7796.961015194887, 7939.960300198462, 8155.592220389076, 8246.134155913544, 8336.791580210422, 5984.820455386345, 6304.779332723333, 6305.5901366411035, 6354.7140378684, 6395.072714456471, 9732.75668108303, 10219.080282774288, 10245.129164020844, 10288.691339259834, 10328.741781455523, 10100.0, 10308.12380947628, 10354.72196116853, 10450.059494645518, 10450.059494645518, 6826.726930922708, 7607.087149542091, 7737.3036426721865, 7829.099653539688, 7926.207379262028, 8642.308615310634, 8843.69047083349, 8845.646174152962, 8854.203121719076, 8856.247218986458, 7744.0, 7763.72826951054, 7889.5660738657625, 7986.960065199637, 8087.231712987257, 7698.499597526143, 7821.178776228414, 7871.48835325702, 7903.170167132368, 7917.049954005556, 7208.56748595086, 7342.339189472973, 7346.485745997729, 7349.4487913407775, 7355.411567074728, 5200.2199670049795, 5300.310959575385, 5364.758585863724, 5503.0, 5555.166724991283, 5415.620906536505, 5431.348238211282, 5439.619226654097, 5513.2005859151, 5539.584531160099], "value": 4658.339573783072, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.52944407273895, 345.1474857102956, 348.84650753668376, 349.74267508307656, 352.88884001539515, 5155.510226528483, 5183.559397451221, 5333.413324534298, 5366.155937353199, 5377.354717433914, 5157.432682404932, 5181.378234611851, 5187.48125187481, 5242.108841496945, 5252.133397989327, 4157.625813676768, 4162.271602469578, 4164.229617520768, 4166.958330416687, 4191.350340697197, 3935.5277366716036, 4043.65628921542, 4167.395727619493, 4173.853915112972, 4215.3044747616605, 4021.4973128358956, 4039.757614543122, 4044.352903535435, 4048.7975601219823, 4049.7165198436123, 4030.576789437111, 4049.7367671101433, 4055.1281474482835, 4065.2276067547073, 4068.9796551017353, 3612.06754386402, 3619.366610843092, 3621.0, 3622.366085934977, 3625.220577575834, 5022.89954200914, 5048.343715317022, 5049.141645920203, 5071.41678706946, 5082.526067440438, 4600.723956562617, 4603.861884143481, 4616.953830461686, 4624.838130665411, 4637.559431853969, 5350.973245133789, 5361.463853614647, 5375.354957405098, 5394.811181608625, 5407.405185429599, 3833.9616603833883, 3925.2934471795065, 3938.822752976123, 3939.625735555118, 3950.545687245975, 5654.773809047633, 5670.858228544278, 5676.1769543416385, 5679.829605111853, 5688.317401911797, 4039.1517781265675, 4045.8583949561626, 4053.61490658387, 4064.4106604542176, 4082.4284600156, 4001.7598944063448, 4081.4081958115908, 4094.6314831665295, 4096.303628383182, 4105.885468983476, 4619.376384188092, 4625.722456652612, 4627.282771170474, 4628.74541900192, 4631.143238500888, 5437.401885792559, 5476.644018138808, 5482.643628164156, 5493.890122197614, 5500.28496295479, 1339.7521458530202, 1374.1892283552552, 1385.9307034648227, 1406.1773862290675, 1408.1621435246013, 934.8737920380797, 948.1087777489138, 966.9806603867884, 971.5190980464715, 976.9364991275544, 5175.922361164586, 5211.0, 5222.817201397933, 5224.686518808883, 5247.921281180786, 5471.9179212311055, 5474.562035037187, 5497.092979658311, 5521.47545983139, 5527.115661494222, 5058.165402708585, 5187.054854429589, 5234.712090834964, 5247.553957913544, 5343.0, 2955.2907302247304, 2977.7320041196394, 2980.1208643450236, 2988.372441787249, 2990.4168687105807, 5733.31200255972, 5744.080947048452, 5748.419184724151, 5754.88490230193, 5766.596338256366, 5576.832695019156, 5723.627964182315, 5733.913991290134, 5742.569307301969, 5758.107493338539, 5724.971375143181, 5746.971265143731, 5749.425057494217, 5755.9424405756245, 5781.739821708034, 5378.193271009223, 5586.692731899784, 5606.27118474612, 5619.213310136666, 5656.8020119295625, 5528.861778455571, 5545.861353466034, 5548.778048878, 5559.805406810904, 5564.527015203753, 5762.423757624204, 5768.826935191783, 5769.740361683818, 5775.393583673822, 5781.392953739965, 3966.2067586483395, 4056.1684854605323, 4066.2070896176147, 4076.184763047343, 4079.082206503513, 5350.919736203882, 5586.273784408001, 5606.915896261477, 5616.522595579421, 5620.185073164286, 5343.7862485500145, 5476.945230547643, 5477.0, 5481.917771233514, 5491.462390530658, 5463.235147079333, 5464.91802622953, 5465.398806131402, 5465.562754979672, 5472.248880358247, 5231.39838918514, 5464.808731694372, 5467.699276539828, 5473.2063850742215, 5507.7246137693755, 5488.286522752017, 5527.64070335443, 5532.529734972572, 5533.861653458534, 5544.556435485232, 5290.867728306823, 5329.307190065267, 5335.439778823167, 5338.172583249602, 5340.759665815125], "value": 4185.878956267443, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.6082193971375, 844.9831003379934, 845.7716416567523, 848.1094850407071, 849.4436144325465, 15774.84225157748, 16393.83606163938, 16956.4065257716, 17119.74320385195, 17124.143792810373, 16644.83355166448, 16763.83236167638, 16765.413210537634, 16787.580310492212, 16792.748108778378, 672.9158855143107, 811.3549727966281, 830.9875351869727, 833.8290650416681, 833.9666413343459, 798.648594618366, 817.8159914019358, 833.0836080311669, 835.3192148399065, 835.6448509383488, 877.3814460805118, 878.9121087891192, 879.4371602174614, 879.9032106468284, 880.0891077734547, 705.1432509500981, 845.488479469918, 866.653338664536, 870.9390342675983, 872.4111224923183, 627.2379059442799, 636.5893998371071, 636.6912047656883, 636.7834936121741, 637.2512298049819, 16107.033577985241, 16118.274677639534, 16127.0, 16143.596410089724, 16146.919265403716, 14374.137551746928, 14635.92682036583, 14669.70660586793, 14927.40290388383, 14975.1014939104, 13529.458821647122, 13678.658033549142, 13923.164610123426, 13958.441662333495, 14103.78844317336, 4454.844080457168, 4556.031110956111, 4556.430446194226, 4729.952700472985, 4730.62155027597, 85356.57321713351, 86730.5663471685, 87406.0, 88982.5550872248, 90562.0, 11558.190926635054, 11699.356535390636, 11836.644900652995, 11866.718050573561, 12128.878711212952, 22308.665370019462, 22343.106275749113, 22370.776292237195, 22415.663765043537, 22508.099676012775, 8450.704225352207, 8454.788630284293, 8457.534835584102, 8540.957295213484, 8620.0, 4505.7521836298665, 4588.334691469785, 4624.1676498230645, 4678.508756580578, 4724.740139292372, 3365.983170084183, 3495.965040349615, 3501.6148223695373, 3502.562179727534, 3508.8771892983623, 588.4406323243439, 593.5370411079365, 600.579594284003, 604.1451346344872, 616.5283558078074, 676.9221539523018, 707.5012116457884, 759.5063208914139, 768.5503980171662, 778.1401551285747, 9596.08078384312, 9985.301028927905, 10031.634865999471, 10202.234832387601, 10792.838107428395, 718.9636595647456, 743.0013264319073, 773.8220209351881, 780.0911937592641, 786.4101923557332, 4330.783460826946, 4793.832215872428, 4883.790523690773, 4944.964432796705, 5013.523715247045, 109386.0, 111006.0, 112262.38688065564, 112947.0, 114909.0, 87155.56422217848, 98389.50805245928, 100868.49565752124, 101140.49429752951, 101220.0, 88267.55866220756, 90949.0, 91559.85683149696, 91986.54006730058, 94279.0572094284, 8919.955400223216, 9029.77425564366, 9202.825228657046, 9243.306751993761, 9351.0, 48246.276305854735, 49797.75101124616, 51143.488565113876, 51158.488415115375, 51454.0, 69271.65364173146, 71844.0, 76536.23463765292, 79101.60449197948, 79334.20665793268, 12368.938155309166, 12462.376881155724, 12661.81007284873, 12688.17526860751, 12819.27374719162, 13451.596452106452, 13696.209944367458, 13889.65275868071, 14208.218547979961, 15344.923275383551, 12574.937125314314, 12672.303023333805, 12741.49034038628, 13182.868171318163, 13877.097988630707, 13081.888039516747, 13222.603321900358, 13485.865141348853, 13591.864081359456, 13643.79534307006, 12505.249685018927, 12729.6181114563, 12932.288724120264, 13267.933660331637, 13286.402111904794, 10054.330298444458, 10135.061160709736, 10357.482125893826, 10411.895881041093, 10765.89234107649, 10338.534765935401, 10606.628767993083, 10619.522121504397, 10658.413787241776, 10679.19906007037], "value": 7705.9677925445785, "unit": "score"}], "name": "dromaeo_css", "value": 7223.379017377304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 131.0, 111.0, 113.0, 111.0, 109.0, 111.0, 111.0, 111.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 144.0, 176.0, 118.0, 148.0, 148.0, 118.0, 190.0, 116.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 162.0, 166.0, 160.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 173.0, 75.0, 75.0, 77.0, 77.0, 168.0, 74.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 155.0, 82.0, 82.0, 81.0, 152.0, 82.0, 82.0, 138.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 148.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 53.0, 54.0, 54.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 80.0, 94.0, 89.0, 79.0, 79.0, 79.0, 88.0, 79.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 119.0, 135.0, 122.0, 139.0, 123.0, 122.0, 162.0, 136.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 166.0, 165.0, 167.0, 165.0, 164.0, 164.0, 164.0, 164.0, 167.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 66.0, 65.0, 66.0, 65.0, 68.0, 65.0, 63.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1531.2999999999997, "alertThreshold": 2.0}]} 05:44:26 INFO - Return code: 0 05:44:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:44:26 INFO - # TBPL SUCCESS # 05:44:26 INFO - Running post-action listener: _resource_record_post_action 05:44:26 INFO - [mozharness: 2016-08-30 12:44:26.725874Z] Finished run-tests step (success) 05:44:26 INFO - Running post-run listener: _resource_record_post_run 05:44:26 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 12288; Write bytes: 418070528; Read time: 300; Write time: 465532 05:44:26 INFO - TinderboxPrint: CPU usage
13.0% 05:44:26 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 300 05:44:26 INFO - TinderboxPrint: I/O write bytes / time
418,070,528 / 465,532 05:44:26 INFO - TinderboxPrint: CPU idle
8,304.6 (86.9%) 05:44:26 INFO - TinderboxPrint: CPU user
1,191.5 (12.5%) 05:44:26 INFO - TinderboxPrint: Swap in / out
0 / 0 05:44:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 129101824; Read time: 164; Write time: 106596 05:44:26 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288354304; Read time: 124; Write time: 358936 05:44:27 INFO - Running post-run listener: _upload_blobber_files 05:44:27 INFO - Blob upload gear active. 05:44:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:27 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 05:44:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:44:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:44:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:28 INFO - (blobuploader) - INFO - Done attempting. 05:44:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:44:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:44:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:29 INFO - (blobuploader) - INFO - Done attempting. 05:44:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:44:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:32 INFO - (blobuploader) - INFO - Done attempting. 05:44:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:32 INFO - Return code: 0 05:44:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae42f6a78345c94ebaf99ba384de48530a8844d5fd3dbaa8cf81633101082bf8eecc9e4b294bc23b60056d69c68b23a1932be6ad4bbc4a92dd11ab40b2c91950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6dc10d425fa1ad35e6ec2cc6ad7dd475fe6a00e93de7464ed497c1884cdd72bf181394d1bb1e40b19936fe834a1e59f8b20f717c325fe291dcef2313bbc0a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a7ff0565fe7ced19a395e1152f34650d24557c9c3647c419e9262c353b391bd5709f54a0d7a6165a7afd1635ddea49ba3a68f42f75271961266518065ddc129"} 05:44:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:32 INFO - Contents: 05:44:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae42f6a78345c94ebaf99ba384de48530a8844d5fd3dbaa8cf81633101082bf8eecc9e4b294bc23b60056d69c68b23a1932be6ad4bbc4a92dd11ab40b2c91950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6dc10d425fa1ad35e6ec2cc6ad7dd475fe6a00e93de7464ed497c1884cdd72bf181394d1bb1e40b19936fe834a1e59f8b20f717c325fe291dcef2313bbc0a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a7ff0565fe7ced19a395e1152f34650d24557c9c3647c419e9262c353b391bd5709f54a0d7a6165a7afd1635ddea49ba3a68f42f75271961266518065ddc129"} 05:44:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:32 INFO - Copying logs to upload dir... 05:44:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.668454 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-30 05:44:32.639066) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 05:44:32.644453) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae42f6a78345c94ebaf99ba384de48530a8844d5fd3dbaa8cf81633101082bf8eecc9e4b294bc23b60056d69c68b23a1932be6ad4bbc4a92dd11ab40b2c91950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6dc10d425fa1ad35e6ec2cc6ad7dd475fe6a00e93de7464ed497c1884cdd72bf181394d1bb1e40b19936fe834a1e59f8b20f717c325fe291dcef2313bbc0a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a7ff0565fe7ced19a395e1152f34650d24557c9c3647c419e9262c353b391bd5709f54a0d7a6165a7afd1635ddea49ba3a68f42f75271961266518065ddc129"} build_url:https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011490 build_url: 'https://queue.taskcluster.net/v1/task/VZZF9KdcTry4Iiwkj9ecpw/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/Fx-Team/sha512/ae42f6a78345c94ebaf99ba384de48530a8844d5fd3dbaa8cf81633101082bf8eecc9e4b294bc23b60056d69c68b23a1932be6ad4bbc4a92dd11ab40b2c91950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6dc10d425fa1ad35e6ec2cc6ad7dd475fe6a00e93de7464ed497c1884cdd72bf181394d1bb1e40b19936fe834a1e59f8b20f717c325fe291dcef2313bbc0a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a7ff0565fe7ced19a395e1152f34650d24557c9c3647c419e9262c353b391bd5709f54a0d7a6165a7afd1635ddea49ba3a68f42f75271961266518065ddc129"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 05:44:32.672772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:44:32.673114) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472558901.710550-690030914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 05:44:32.755761) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 05:44:32.756081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 05:44:32.756397) ========= ========= Total master_lag: 0.18 =========