builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1476635039.58 results: success (0) buildid: 20161016061936 builduid: 8d5c27dfb8be4bb7a97943d2e59361dc revision: f0596b7956ec3ee2bd1e329c2abfcc183fbead50 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.583515) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.583938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.584223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.602342) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.602595) ========= 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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-16 09:23:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 81.1M=0s 2016-10-16 09:23:59 (81.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102303 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.717943) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.718239) ========= 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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014467 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.767581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:23:59.767893) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f0596b7956ec3ee2bd1e329c2abfcc183fbead50 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f0596b7956ec3ee2bd1e329c2abfcc183fbead50 --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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-16 09:24:00,055 truncating revision to first 12 chars 2016-10-16 09:24:00,055 Setting DEBUG logging. 2016-10-16 09:24:00,055 attempt 1/10 2016-10-16 09:24:00,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f0596b7956ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 09:24:00,680 unpacking tar archive at: mozilla-aurora-f0596b7956ec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.957818 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:24:00.738609) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 09:24:00.738907) ========= script_repo_revision: f0596b7956ec3ee2bd1e329c2abfcc183fbead50 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 09:24:00.739318) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 09:24:00.739593) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 09:24:00.747516) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-16 09:24:00.747788) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:24:01 INFO - MultiFileLogger online at 20161016 09:24:01 in /builds/slave/test 09:24:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:24:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:24:01 INFO - {'append_to_log': False, 09:24:01 INFO - 'base_work_dir': '/builds/slave/test', 09:24:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:24:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:24:01 INFO - 'branch': 'Mozilla-Aurora', 09:24:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:24:01 INFO - 'config_files': ('talos/linux_config.py',), 09:24:01 INFO - 'default_actions': ('clobber', 09:24:01 INFO - 'read-buildbot-config', 09:24:01 INFO - 'download-and-extract', 09:24:01 INFO - 'populate-webroot', 09:24:01 INFO - 'create-virtualenv', 09:24:01 INFO - 'install', 09:24:01 INFO - 'run-tests'), 09:24:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:24:01 INFO - 'download_minidump_stackwalk': True, 09:24:01 INFO - 'download_symbols': 'ondemand', 09:24:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:24:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:24:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:24:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:24:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:24:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:24:01 INFO - 'installer_path': 'installer.exe', 09:24:01 INFO - 'log_level': 'info', 09:24:01 INFO - 'log_name': 'talos', 09:24:01 INFO - 'log_to_console': True, 09:24:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:24:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:24:01 INFO - 'opt_config_files': (), 09:24:01 INFO - 'pip_index': False, 09:24:01 INFO - 'sps_profile': False, 09:24:01 INFO - 'sps_profile_interval': 0, 09:24:01 INFO - 'suite': 'dromaeojs', 09:24:01 INFO - 'system_bits': '32', 09:24:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:24:01 INFO - 'title': 'talos-linux64-ix-015', 09:24:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:24:01 INFO - 'use_talos_json': True, 09:24:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:24:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:24:01 INFO - 'work_dir': 'build'} 09:24:01 INFO - [mozharness: 2016-10-16 16:24:01.128358Z] Running clobber step. 09:24:01 INFO - Running pre-action listener: _resource_record_pre_action 09:24:01 INFO - Running main action method: clobber 09:24:01 INFO - rmtree: /builds/slave/test/build 09:24:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:24:01 INFO - Running post-action listener: _resource_record_post_action 09:24:01 INFO - [mozharness: 2016-10-16 16:24:01.582202Z] Finished clobber step (success) 09:24:01 INFO - [mozharness: 2016-10-16 16:24:01.582305Z] Running read-buildbot-config step. 09:24:01 INFO - Running pre-action listener: _resource_record_pre_action 09:24:01 INFO - Running main action method: read_buildbot_config 09:24:01 INFO - Using buildbot properties: 09:24:01 INFO - { 09:24:01 INFO - "project": "", 09:24:01 INFO - "product": "firefox", 09:24:01 INFO - "script_repo_revision": "production", 09:24:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:24:01 INFO - "repository": "", 09:24:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:24:01 INFO - "buildid": "20161016061936", 09:24:01 INFO - "pgo_build": "True", 09:24:01 INFO - "basedir": "/builds/slave/test", 09:24:01 INFO - "buildnumber": 519, 09:24:01 INFO - "slavename": "talos-linux64-ix-015", 09:24:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:24:01 INFO - "platform": "ubuntu64_hw", 09:24:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:24:01 INFO - "branch": "mozilla-aurora", 09:24:01 INFO - "repo_path": "releases/mozilla-aurora", 09:24:01 INFO - "revision": "f0596b7956ec3ee2bd1e329c2abfcc183fbead50", 09:24:01 INFO - "stage_platform": "linux64-pgo", 09:24:01 INFO - "builduid": "8d5c27dfb8be4bb7a97943d2e59361dc", 09:24:01 INFO - "slavebuilddir": "test" 09:24:01 INFO - } 09:24:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:24:01 INFO - Running post-action listener: _resource_record_post_action 09:24:01 INFO - [mozharness: 2016-10-16 16:24:01.584768Z] Finished read-buildbot-config step (success) 09:24:01 INFO - [mozharness: 2016-10-16 16:24:01.584859Z] Running download-and-extract step. 09:24:01 INFO - Running pre-action listener: _resource_record_pre_action 09:24:01 INFO - Running main action method: download_and_extract 09:24:01 INFO - mkdir: /builds/slave/test/build/tests 09:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:01 INFO - trying https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:24:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:24:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:24:02 INFO - Downloaded 1361 bytes. 09:24:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:24:02 INFO - Using the following test package requirements: 09:24:02 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:24:02 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:24:02 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:24:02 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'jsshell-linux-x86_64.zip'], 09:24:02 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:24:02 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:24:02 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:24:02 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:24:02 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:24:02 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:24:02 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:24:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:24:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:24:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:24:02 INFO - Expected file size: 21906928 09:24:07 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:24:07 INFO - Expected file size: 21906928 09:24:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:24:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:24:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:24:13 INFO - Expected file size: 14414081 09:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:14 INFO - trying https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:24:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:24:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:24:15 INFO - Downloaded 64000510 bytes. 09:24:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:24:15 INFO - mkdir: /builds/slave/test/properties 09:24:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:15 INFO - Contents: 09:24:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:24:16 INFO - Running post-action listener: _resource_record_post_action 09:24:16 INFO - Running post-action listener: set_extra_try_arguments 09:24:16 INFO - [mozharness: 2016-10-16 16:24:16.429588Z] Finished download-and-extract step (success) 09:24:16 INFO - [mozharness: 2016-10-16 16:24:16.429855Z] Running populate-webroot step. 09:24:16 INFO - Running pre-action listener: _resource_record_pre_action 09:24:16 INFO - Running main action method: populate_webroot 09:24:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:24:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:24:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:24:16 INFO - '--tppagecycles', 09:24:16 INFO - '7'], 09:24:16 INFO - 'tests': ['tsvgm']}}, 09:24:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['damp', 'tps']}, 09:24:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'tests': ['damp', 'tps']}, 09:24:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['dromaeo_dom']}, 09:24:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['basic_compositor_video']}, 09:24:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:24:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['a11yr', 09:24:16 INFO - 'ts_paint', 09:24:16 INFO - 'tpaint', 09:24:16 INFO - 'sessionrestore', 09:24:16 INFO - 'sessionrestore_no_auto_restore', 09:24:16 INFO - 'tabpaint']}, 09:24:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['tsvgx', 09:24:16 INFO - 'tsvgr_opacity', 09:24:16 INFO - 'tart', 09:24:16 INFO - 'tscrollx', 09:24:16 INFO - 'cart']}, 09:24:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:16 INFO - 'tsvgr_opacity', 09:24:16 INFO - 'tart', 09:24:16 INFO - 'tscrollx', 09:24:16 INFO - 'cart']}, 09:24:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'talos_options': ['--disable-e10s'], 09:24:16 INFO - 'tests': ['tp5o']}, 09:24:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'tests': ['tp5o']}, 09:24:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'talos_options': ['--disable-e10s', 09:24:16 INFO - '--xperf_path', 09:24:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:16 INFO - 'tests': ['tp5n']}, 09:24:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:16 INFO - 'talos_options': ['--xperf_path', 09:24:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:16 INFO - 'tests': ['tp5n']}}, 09:24:16 INFO - 'talos.zip': {'path': '', 09:24:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:24:16 INFO - Running post-action listener: _resource_record_post_action 09:24:16 INFO - [mozharness: 2016-10-16 16:24:16.448290Z] Finished populate-webroot step (success) 09:24:16 INFO - [mozharness: 2016-10-16 16:24:16.448379Z] Running create-virtualenv step. 09:24:16 INFO - Running pre-action listener: _resource_record_pre_action 09:24:16 INFO - Running main action method: create_virtualenv 09:24:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:24:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:24:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:16 INFO - Using real prefix '/usr' 09:24:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:19 INFO - Installing pip.................done. 09:24:19 INFO - Return code: 0 09:24:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:24:19 INFO - Using env: {'DISPLAY': ':0', 09:24:19 INFO - 'HOME': '/home/cltbld', 09:24:19 INFO - 'LANG': 'en_US.UTF-8', 09:24:19 INFO - 'LANGUAGE': 'en_US:en', 09:24:19 INFO - 'LOGNAME': 'cltbld', 09:24:19 INFO - 'MAIL': '/var/mail/cltbld', 09:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:19 INFO - 'MOZ_NO_REMOTE': '1', 09:24:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:19 INFO - 'NO_EM_RESTART': '1', 09:24:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:19 INFO - 'PWD': '/builds/slave/test', 09:24:19 INFO - 'SHELL': '/bin/bash', 09:24:19 INFO - 'SHLVL': '1', 09:24:19 INFO - 'TERM': 'linux', 09:24:19 INFO - 'TMOUT': '86400', 09:24:19 INFO - 'USER': 'cltbld', 09:24:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:19 INFO - '_': '/tools/buildbot/bin/python'} 09:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:24:19 INFO - Cleaning up... 09:24:19 INFO - Return code: 0 09:24:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:24:19 INFO - Using env: {'DISPLAY': ':0', 09:24:19 INFO - 'HOME': '/home/cltbld', 09:24:19 INFO - 'LANG': 'en_US.UTF-8', 09:24:19 INFO - 'LANGUAGE': 'en_US:en', 09:24:19 INFO - 'LOGNAME': 'cltbld', 09:24:19 INFO - 'MAIL': '/var/mail/cltbld', 09:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:19 INFO - 'MOZ_NO_REMOTE': '1', 09:24:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:19 INFO - 'NO_EM_RESTART': '1', 09:24:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:19 INFO - 'PWD': '/builds/slave/test', 09:24:19 INFO - 'SHELL': '/bin/bash', 09:24:19 INFO - 'SHLVL': '1', 09:24:19 INFO - 'TERM': 'linux', 09:24:19 INFO - 'TMOUT': '86400', 09:24:19 INFO - 'USER': 'cltbld', 09:24:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:19 INFO - '_': '/tools/buildbot/bin/python'} 09:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:19 INFO - Downloading/unpacking psutil>=3.1.1 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:21 INFO - Installing collected packages: psutil 09:24:21 INFO - Running setup.py install for psutil 09:24:21 INFO - building 'psutil._psutil_linux' extension 09:24:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:24:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:24:21 INFO - building 'psutil._psutil_posix' extension 09:24:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:24:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:21 INFO - Successfully installed psutil 09:24:21 INFO - Cleaning up... 09:24:22 INFO - Return code: 0 09:24:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:24:22 INFO - Using env: {'DISPLAY': ':0', 09:24:22 INFO - 'HOME': '/home/cltbld', 09:24:22 INFO - 'LANG': 'en_US.UTF-8', 09:24:22 INFO - 'LANGUAGE': 'en_US:en', 09:24:22 INFO - 'LOGNAME': 'cltbld', 09:24:22 INFO - 'MAIL': '/var/mail/cltbld', 09:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:22 INFO - 'MOZ_NO_REMOTE': '1', 09:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:22 INFO - 'NO_EM_RESTART': '1', 09:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:22 INFO - 'PWD': '/builds/slave/test', 09:24:22 INFO - 'SHELL': '/bin/bash', 09:24:22 INFO - 'SHLVL': '1', 09:24:22 INFO - 'TERM': 'linux', 09:24:22 INFO - 'TMOUT': '86400', 09:24:22 INFO - 'USER': 'cltbld', 09:24:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:22 INFO - '_': '/tools/buildbot/bin/python'} 09:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:24 INFO - Installing collected packages: mozsystemmonitor 09:24:24 INFO - Running setup.py install for mozsystemmonitor 09:24:24 INFO - Successfully installed mozsystemmonitor 09:24:24 INFO - Cleaning up... 09:24:24 INFO - Return code: 0 09:24:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:24 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:24 INFO - Using env: {'DISPLAY': ':0', 09:24:24 INFO - 'HOME': '/home/cltbld', 09:24:24 INFO - 'LANG': 'en_US.UTF-8', 09:24:24 INFO - 'LANGUAGE': 'en_US:en', 09:24:24 INFO - 'LOGNAME': 'cltbld', 09:24:24 INFO - 'MAIL': '/var/mail/cltbld', 09:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:24 INFO - 'MOZ_NO_REMOTE': '1', 09:24:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:24 INFO - 'NO_EM_RESTART': '1', 09:24:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:24 INFO - 'PWD': '/builds/slave/test', 09:24:24 INFO - 'SHELL': '/bin/bash', 09:24:24 INFO - 'SHLVL': '1', 09:24:24 INFO - 'TERM': 'linux', 09:24:24 INFO - 'TMOUT': '86400', 09:24:24 INFO - 'USER': 'cltbld', 09:24:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:24 INFO - '_': '/tools/buildbot/bin/python'} 09:24:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09: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 09: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 09: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 09: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 09: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 09: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 09:24:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:26 INFO - warning: no files found matching '*.txt' 09:24:26 INFO - no previously-included directories found matching 'build' 09:24:26 INFO - no previously-included directories found matching 'dist' 09:24:26 INFO - no previously-included directories found matching '.git*' 09:24:26 INFO - Installing collected packages: jsonschema, functools32 09:24:26 INFO - Running setup.py install for functools32 09:24:26 INFO - warning: no files found matching '*.txt' 09:24:26 INFO - no previously-included directories found matching 'build' 09:24:26 INFO - no previously-included directories found matching 'dist' 09:24:26 INFO - no previously-included directories found matching '.git*' 09:24:26 INFO - Successfully installed jsonschema functools32 09:24:26 INFO - Cleaning up... 09:24:26 INFO - Return code: 0 09:24:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:26 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:24:26 INFO - Using env: {'DISPLAY': ':0', 09:24:26 INFO - 'HOME': '/home/cltbld', 09:24:26 INFO - 'LANG': 'en_US.UTF-8', 09:24:26 INFO - 'LANGUAGE': 'en_US:en', 09:24:26 INFO - 'LOGNAME': 'cltbld', 09:24:26 INFO - 'MAIL': '/var/mail/cltbld', 09:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:26 INFO - 'MOZ_NO_REMOTE': '1', 09:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:26 INFO - 'NO_EM_RESTART': '1', 09:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:26 INFO - 'PWD': '/builds/slave/test', 09:24:26 INFO - 'SHELL': '/bin/bash', 09:24:26 INFO - 'SHLVL': '1', 09:24:26 INFO - 'TERM': 'linux', 09:24:26 INFO - 'TMOUT': '86400', 09:24:26 INFO - 'USER': 'cltbld', 09:24:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:26 INFO - '_': '/tools/buildbot/bin/python'} 09:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:27 INFO - Cleaning up... 09:24:27 INFO - Return code: 0 09:24:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:27 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:27 INFO - Using env: {'DISPLAY': ':0', 09:24:27 INFO - 'HOME': '/home/cltbld', 09:24:27 INFO - 'LANG': 'en_US.UTF-8', 09:24:27 INFO - 'LANGUAGE': 'en_US:en', 09:24:27 INFO - 'LOGNAME': 'cltbld', 09:24:27 INFO - 'MAIL': '/var/mail/cltbld', 09:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:27 INFO - 'MOZ_NO_REMOTE': '1', 09:24:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:27 INFO - 'NO_EM_RESTART': '1', 09:24:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:27 INFO - 'PWD': '/builds/slave/test', 09:24:27 INFO - 'SHELL': '/bin/bash', 09:24:27 INFO - 'SHLVL': '1', 09:24:27 INFO - 'TERM': 'linux', 09:24:27 INFO - 'TMOUT': '86400', 09:24:27 INFO - 'USER': 'cltbld', 09:24:27 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:27 INFO - '_': '/tools/buildbot/bin/python'} 09:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:30 INFO - Downloading docopt-0.6.1.tar.gz 09:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:30 INFO - Running setup.py install for blobuploader 09:24:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:30 INFO - Running setup.py install for requests 09:24:30 INFO - Running setup.py install for docopt 09:24:30 INFO - Successfully installed blobuploader requests docopt 09:24:30 INFO - Cleaning up... 09:24:30 INFO - Return code: 0 09:24:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:30 INFO - Using env: {'DISPLAY': ':0', 09:24:30 INFO - 'HOME': '/home/cltbld', 09:24:30 INFO - 'LANG': 'en_US.UTF-8', 09:24:30 INFO - 'LANGUAGE': 'en_US:en', 09:24:30 INFO - 'LOGNAME': 'cltbld', 09:24:30 INFO - 'MAIL': '/var/mail/cltbld', 09:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:30 INFO - 'MOZ_NO_REMOTE': '1', 09:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:30 INFO - 'NO_EM_RESTART': '1', 09:24:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:30 INFO - 'PWD': '/builds/slave/test', 09:24:30 INFO - 'SHELL': '/bin/bash', 09:24:30 INFO - 'SHLVL': '1', 09:24:30 INFO - 'TERM': 'linux', 09:24:30 INFO - 'TMOUT': '86400', 09:24:30 INFO - 'USER': 'cltbld', 09:24:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:30 INFO - '_': '/tools/buildbot/bin/python'} 09:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:30 INFO - Running setup.py (path:/tmp/pip-lPuoF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:31 INFO - Running setup.py (path:/tmp/pip-8YpBaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:31 INFO - Running setup.py (path:/tmp/pip-PMhz99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:31 INFO - Running setup.py (path:/tmp/pip-xPZlU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:31 INFO - Running setup.py (path:/tmp/pip-eV7f_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:31 INFO - Running setup.py (path:/tmp/pip-Em8fgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:31 INFO - Running setup.py (path:/tmp/pip-sdP3X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:31 INFO - Running setup.py (path:/tmp/pip-6u2rqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:31 INFO - Running setup.py (path:/tmp/pip-LpfWt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:31 INFO - Running setup.py (path:/tmp/pip-6Zz88g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:31 INFO - Running setup.py (path:/tmp/pip-SvSJjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:31 INFO - Running setup.py (path:/tmp/pip-LXMNkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:31 INFO - Running setup.py (path:/tmp/pip-zj7kNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:31 INFO - Running setup.py (path:/tmp/pip-f06ssG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:31 INFO - Running setup.py (path:/tmp/pip-qm6wuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:32 INFO - Running setup.py (path:/tmp/pip-0fdGvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:32 INFO - Running setup.py (path:/tmp/pip-i3u2W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:32 INFO - Running setup.py install for manifestparser 09:24:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Running setup.py install for mozcrash 09:24:32 INFO - Running setup.py install for mozdebug 09:24:32 INFO - Running setup.py install for mozdevice 09:24:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Running setup.py install for mozfile 09:24:32 INFO - Running setup.py install for mozhttpd 09:24:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Running setup.py install for mozinfo 09:24:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Running setup.py install for mozInstall 09:24:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:32 INFO - Running setup.py install for mozleak 09:24:33 INFO - Running setup.py install for mozlog 09:24:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Running setup.py install for moznetwork 09:24:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Running setup.py install for mozprocess 09:24:33 INFO - Running setup.py install for mozprofile 09:24:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Running setup.py install for mozrunner 09:24:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Running setup.py install for mozscreenshot 09:24:33 INFO - Running setup.py install for moztest 09:24:33 INFO - Running setup.py install for mozversion 09:24:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:33 INFO - Cleaning up... 09:24:33 INFO - Return code: 0 09:24:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:33 INFO - Using env: {'DISPLAY': ':0', 09:24:33 INFO - 'HOME': '/home/cltbld', 09:24:33 INFO - 'LANG': 'en_US.UTF-8', 09:24:33 INFO - 'LANGUAGE': 'en_US:en', 09:24:33 INFO - 'LOGNAME': 'cltbld', 09:24:33 INFO - 'MAIL': '/var/mail/cltbld', 09:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:33 INFO - 'MOZ_NO_REMOTE': '1', 09:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:33 INFO - 'NO_EM_RESTART': '1', 09:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:33 INFO - 'PWD': '/builds/slave/test', 09:24:33 INFO - 'SHELL': '/bin/bash', 09:24:33 INFO - 'SHLVL': '1', 09:24:33 INFO - 'TERM': 'linux', 09:24:33 INFO - 'TMOUT': '86400', 09:24:33 INFO - 'USER': 'cltbld', 09:24:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:33 INFO - '_': '/tools/buildbot/bin/python'} 09:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:34 INFO - Running setup.py (path:/tmp/pip-VgDrfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:34 INFO - Running setup.py (path:/tmp/pip-LaMgyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:34 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)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:34 INFO - Running setup.py (path:/tmp/pip-_4GCNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:34 INFO - Running setup.py (path:/tmp/pip-7Pu4sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:34 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)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:34 INFO - Running setup.py (path:/tmp/pip-pbDNpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:34 INFO - Running setup.py (path:/tmp/pip-Rdf40m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:34 INFO - Running setup.py (path:/tmp/pip-BqvaWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:34 INFO - Running setup.py (path:/tmp/pip-laDdDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:34 INFO - Running setup.py (path:/tmp/pip-jhrQU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:34 INFO - Running setup.py (path:/tmp/pip-it2SpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:34 INFO - Running setup.py (path:/tmp/pip-M0FX4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:34 INFO - Running setup.py (path:/tmp/pip-NrNA0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:35 INFO - Running setup.py (path:/tmp/pip-ImlTfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:35 INFO - Running setup.py (path:/tmp/pip-hhbbgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:35 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)) 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:35 INFO - Running setup.py (path:/tmp/pip-xn9Rit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:35 INFO - Running setup.py (path:/tmp/pip-UN8ter-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:35 INFO - Running setup.py (path:/tmp/pip-rjIiTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:24:35 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)) 09:24:35 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)) 09:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:35 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)) 09:24:35 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)) 09:24:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:37 INFO - Downloading blessings-1.6.tar.gz 09:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:37 INFO - Installing collected packages: blessings 09:24:37 INFO - Running setup.py install for blessings 09:24:37 INFO - Successfully installed blessings 09:24:37 INFO - Cleaning up... 09:24:37 INFO - Return code: 0 09:24:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:37 INFO - Reading from file tmpfile_stdout 09:24:37 INFO - Current package versions: 09:24:37 INFO - argparse == 1.2.1 09:24:37 INFO - blessings == 1.6 09:24:37 INFO - blobuploader == 1.2.4 09:24:37 INFO - docopt == 0.6.1 09:24:37 INFO - functools32 == 3.2.3-2 09:24:37 INFO - jsonschema == 2.5.1 09:24:37 INFO - manifestparser == 1.1 09:24:37 INFO - mozInstall == 1.12 09:24:37 INFO - mozcrash == 0.17 09:24:37 INFO - mozdebug == 0.1 09:24:37 INFO - mozdevice == 0.48 09:24:37 INFO - mozfile == 1.2 09:24:37 INFO - mozhttpd == 0.7 09:24:37 INFO - mozinfo == 0.9 09:24:37 INFO - mozleak == 0.1 09:24:37 INFO - mozlog == 3.4 09:24:37 INFO - moznetwork == 0.27 09:24:37 INFO - mozprocess == 0.23 09:24:37 INFO - mozprofile == 0.28 09:24:37 INFO - mozrunner == 6.12 09:24:37 INFO - mozscreenshot == 0.1 09:24:37 INFO - mozsystemmonitor == 0.3 09:24:37 INFO - moztest == 0.7 09:24:37 INFO - mozversion == 1.4 09:24:37 INFO - psutil == 3.1.1 09:24:37 INFO - requests == 1.2.3 09:24:37 INFO - wsgiref == 0.1.2 09:24:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:37 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 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:37 INFO - Using env: {'DISPLAY': ':0', 09:24:37 INFO - 'HOME': '/home/cltbld', 09:24:37 INFO - 'LANG': 'en_US.UTF-8', 09:24:37 INFO - 'LANGUAGE': 'en_US:en', 09:24:37 INFO - 'LOGNAME': 'cltbld', 09:24:37 INFO - 'MAIL': '/var/mail/cltbld', 09:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:37 INFO - 'MOZ_NO_REMOTE': '1', 09:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:37 INFO - 'NO_EM_RESTART': '1', 09:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:37 INFO - 'PWD': '/builds/slave/test', 09:24:37 INFO - 'SHELL': '/bin/bash', 09:24:37 INFO - 'SHLVL': '1', 09:24:37 INFO - 'TERM': 'linux', 09:24:37 INFO - 'TMOUT': '86400', 09:24:37 INFO - 'USER': 'cltbld', 09:24:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:37 INFO - '_': '/tools/buildbot/bin/python'} 09:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:38 INFO - Cleaning up... 09:24:38 INFO - Return code: 0 09:24:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e35c6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:24:38 INFO - Using env: {'DISPLAY': ':0', 09:24:38 INFO - 'HOME': '/home/cltbld', 09:24:38 INFO - 'LANG': 'en_US.UTF-8', 09:24:38 INFO - 'LANGUAGE': 'en_US:en', 09:24:38 INFO - 'LOGNAME': 'cltbld', 09:24:38 INFO - 'MAIL': '/var/mail/cltbld', 09:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:38 INFO - 'MOZ_NO_REMOTE': '1', 09:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:38 INFO - 'NO_EM_RESTART': '1', 09:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:38 INFO - 'PWD': '/builds/slave/test', 09:24:38 INFO - 'SHELL': '/bin/bash', 09:24:38 INFO - 'SHLVL': '1', 09:24:38 INFO - 'TERM': 'linux', 09:24:38 INFO - 'TMOUT': '86400', 09:24:38 INFO - 'USER': 'cltbld', 09:24:38 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:38 INFO - '_': '/tools/buildbot/bin/python'} 09:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:38 INFO - Cleaning up... 09:24:38 INFO - Return code: 0 09:24:38 INFO - Running post-action listener: _resource_record_post_action 09:24:38 INFO - Running post-action listener: _start_resource_monitoring 09:24:38 INFO - Starting resource monitoring. 09:24:38 INFO - [mozharness: 2016-10-16 16:24:38.270752Z] Finished create-virtualenv step (success) 09:24:38 INFO - [mozharness: 2016-10-16 16:24:38.271255Z] Running install step. 09:24:38 INFO - Running pre-action listener: _resource_record_pre_action 09:24:38 INFO - Running main action method: install 09:24:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:38 INFO - Reading from file tmpfile_stdout 09:24:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:38 INFO - Reading from file tmpfile_stdout 09:24:38 INFO - Output received: 09:24:38 INFO - Usage: mozinstall [options] installer 09:24:38 INFO - Options: 09:24:38 INFO - -h, --help show this help message and exit 09:24:38 INFO - -d DEST, --destination=DEST 09:24:38 INFO - Directory to install application into. [default: 09:24:38 INFO - "/builds/slave/test"] 09:24:38 INFO - --app=APP Application being installed. [default: firefox] 09:24:38 INFO - mkdir: /builds/slave/test/build/application 09:24:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:24:54 INFO - Reading from file tmpfile_stdout 09:24:54 INFO - Output received: 09:24:54 INFO - /builds/slave/test/build/application/firefox/firefox 09:24:54 INFO - Running post-action listener: _resource_record_post_action 09:24:54 INFO - [mozharness: 2016-10-16 16:24:54.542063Z] Finished install step (success) 09:24:54 INFO - [mozharness: 2016-10-16 16:24:54.542307Z] Running run-tests step. 09:24:54 INFO - Running pre-action listener: _resource_record_pre_action 09:24:54 INFO - Running main action method: run_tests 09:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:54 INFO - Python 2.7.3 09:24:54 INFO - Return code: 0 09:24:54 INFO - grabbing minidump binary from tooltool 09:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:54 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 0x16cff60>, '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 0x16d0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d11d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:24:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:24:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:24:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:54 INFO - Return code: 0 09:24:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:24:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:24:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:24:54 INFO - Using env: {'DISPLAY': ':0', 09:24:54 INFO - 'HOME': '/home/cltbld', 09:24:54 INFO - 'LANG': 'en_US.UTF-8', 09:24:54 INFO - 'LANGUAGE': 'en_US:en', 09:24:54 INFO - 'LOGNAME': 'cltbld', 09:24:54 INFO - 'MAIL': '/var/mail/cltbld', 09:24:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:54 INFO - 'MOZ_NO_REMOTE': '1', 09:24:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:54 INFO - 'NO_EM_RESTART': '1', 09:24:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:54 INFO - 'PWD': '/builds/slave/test', 09:24:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:54 INFO - 'SHELL': '/bin/bash', 09:24:54 INFO - 'SHLVL': '1', 09:24:54 INFO - 'TERM': 'linux', 09:24:54 INFO - 'TMOUT': '86400', 09:24:54 INFO - 'USER': 'cltbld', 09:24:54 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727', 09:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:54 INFO - '_': '/tools/buildbot/bin/python'} 09:24:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:24:54 INFO - mozversion application_buildid: 20161016061936 09:24:54 INFO - mozversion application_changeset: f0596b7956ec3ee2bd1e329c2abfcc183fbead50 09:24:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:24:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:54 INFO - mozversion application_name: Firefox 09:24:54 INFO - mozversion application_remotingname: firefox-dev 09:24:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:24:54 INFO - mozversion application_vendor: Mozilla 09:24:54 INFO - mozversion application_version: 51.0a2 09:24:54 INFO - mozversion platform_buildid: 20161016061936 09:24:54 INFO - mozversion platform_changeset: f0596b7956ec3ee2bd1e329c2abfcc183fbead50 09:24:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:24:54 INFO - mozversion platform_version: 51.0a2 09:24:54 INFO - using testdate: 1476635094 09:24:54 INFO - actual date: 1476635094 09:24:54 INFO - starting webserver on 'localhost:56314' 09:24:54 INFO - SUITE-START | Running 2 tests 09:24:54 INFO - TEST-START | dromaeo_css 09:24:54 INFO - operating with platform_type : linux_ 09:24:54 INFO - Initialising browser for dromaeo_css test... 09:24:54 INFO - TEST-INFO | started process 18124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDhLZO/profile http://localhost:56314/getInfo.html) 09:24:58 INFO - PROCESS | 18124 | __metrics Screen width/height:1600/1200 09:24:58 INFO - PROCESS | 18124 | colorDepth:24 09:24:58 INFO - PROCESS | 18124 | Browser inner width/height: 1024/697 09:24:58 INFO - PROCESS | 18124 | __metrics 09:24:59 INFO - PROCESS | 18124 | 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" 09:24:59 INFO - PROCESS | 18124 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:59 INFO - PROCESS | 18124 | console.error: PushService: 09:24:59 INFO - PROCESS | 18124 | stateChangeProcessEnqueue: Error transitioning state 09:24:59 INFO - PROCESS | 18124 | UnknownError 09:25:00 INFO - TEST-INFO | 18124: exit 0 09:25:00 INFO - Browser initialized. 09:25:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:00 INFO - TEST-INFO | started process 18218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDhLZO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:12 INFO - PROCESS | 18218 | RSS: Main: 144539648 09:25:12 INFO - PROCESS | 18218 | 09:26:03 INFO - PROCESS | 18218 | 1476635163269 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:28:12 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56314/tests/dromaeo/cssquery-ext.html) 09:28:13 INFO - PROCESS | 18218 | RSS: Main: 245444608 09:28:13 INFO - PROCESS | 18218 | 09:31:15 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-ext.html (next: http://localhost:56314/tests/dromaeo/cssquery-jquery.html) 09:31:16 INFO - PROCESS | 18218 | RSS: Main: 229076992 09:31:16 INFO - PROCESS | 18218 | 09:34:18 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56314/tests/dromaeo/cssquery-mootools.html) 09:34:18 INFO - PROCESS | 18218 | RSS: Main: 174764032 09:34:18 INFO - PROCESS | 18218 | 09:37:20 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56314/tests/dromaeo/cssquery-prototype.html) 09:37:21 INFO - PROCESS | 18218 | RSS: Main: 216993792 09:37:21 INFO - PROCESS | 18218 | 09:40:23 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56314/tests/dromaeo/cssquery-yui.html) 09:40:24 INFO - PROCESS | 18218 | RSS: Main: 175382528 09:40:24 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | Cycle 1(1): loaded http://localhost:56314/tests/dromaeo/cssquery-yui.html (next: http://localhost:56314/tests/dromaeo/cssquery-dojo.html) 09:43:27 INFO - PROCESS | 18218 | RSS: Main: 263012352 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | __start_tp_report 09:43:27 INFO - PROCESS | 18218 | _x_x_mozilla_page_load 09:43:27 INFO - PROCESS | 18218 | _x_x_mozilla_page_load_details 09:43:27 INFO - PROCESS | 18218 | |i|pagename|runs| 09:43:27 INFO - PROCESS | 18218 | |0;dojo.html;3069.69303069693;3095.551145083962;3128.077217220921;3135.5923729915107;3172.9687851448284;5444.265024221729;5494.36814766302;5510.752016159267;5525.4474552544725;5533.363663178736;5567.387587365387;5617.045102332603;5620.91568626471;5622.32532096148;5623;5069.340985671857;5080.669756465836;5098.515641014116;5125.820596279131;5185.585153187736;4966.230234313687;4989.176785830334;5118.692878427288;5162.819301324435;5167.354080739908;4960.0823847588135;4973.825916092937;5012.8496145115705;5015.423226328982;5051.242313652963;5067.214581739836;5083.491650834905;5149.665271757323;5152.742362881841;5186.948130518685;4797.376341075673;5034.924476132825;5058.114829904789;5100.566451851597;5121.743912804345;5374.301340825707;5378.596605254583;5386.380566234894;5422.132458806612;5488.807891723811;5815.360310365856;5825.504832089277;5931;5931.970340148272;5932.911006334908;5770.7691692332255;5959.344472108065;5969.611975221596;5980.641161530322;5981.342052374235;5413.26920865682;5461.208124821918;5507.256520369739;5521.171824226398;5571.025070612626;6075;6108.205933228652;6110.4500594945775;6111.083337499411;6112.419320164578;5589.0219211637805;5842.912356314659;5844.8538786529825;5845.561582881301;5847.707614619252;5698.487136157766;5823.621464604787;5851.853703657443;5857.677827719516;5918.112283157475;5864.472197502245;5865.589408741433;5873.442023007808;5875.265591801025;5876.7649294028615;5813.128030795415;5878.853028674232;5883.441073098134;5883.764649414061;5899.997000509924;4422.624076953495;4458.464984201916;4475.216837053503;4513.458384993822;4517.503074661785;4034.8386064557676;4035.1324465240114;4041.7574945503366;4048.554658987509;4066.2883995300704;5614.775408983595;5619.269494965628;5621.325440947113;5626.493615574536;5659.122835960433;5745.052066408995;5797.9420205797405;5817.476427121579;5836;5924.081767326072;5650.180723795109;5654.6889921054735;5657.773689052474;5668;5684.914726279109;5157.767900444489;5167.638265321391;5170.1727723564045;5170.870728231749;5193;5954.7320370582565;5957.404259574095;5960.433758792908;6059.545534084873;6066.66633335162;5831.825045248649;5862.091375836581;5912.349641539513;5945.34601193877;5951.7024148791525;6085.1480792689945;6092.543059270661;6094.451499365078;6095.542834287357;6100.969495152496;5618.747156377891;5642.379338272704;5741.425857414225;5793.565482588907;5794.594378393641;5880;5890.234269545103;5936.5844390892225;5971.134185543159;6029.849253768691;5791.218185545066;5838.095095260242;5846.824595262149;5849.824505264849;5856.238688970407;5648.15277708347;5815.389384114607;5833.445822646841;5833.883322333614;5836.037053886062;5643.71781410936;5683.346415162309;5714.114312281603;5717.799877004285;5812.418758124154;5818.272715910512;5862.853428664317;5864.413558644102;5871.6770577618645;5880.852978675396;5531.280933478622;5576.0799468088935;5722.74247658848;5741.0527263002805;5741.3971532988435 09:43:27 INFO - PROCESS | 18218 | |1;ext.html;592.7688201601376;607.2803727582814;632.1623848400868;657.0407205479999;660.4650233311021;35227.47158792617;47378.289325660146;48178.27732584014;48211.276830847564;52560.47439525604;49641;49945;50116;50177;50240.49759502404;8387.790305242357;9238.584263708133;9388.765280867965;9400.106989835955;9400.24798016157;8295.253427191552;10854.131669466424;11155.776884462306;11160.888391116107;11671.941640291787;11429.199956003084;11491.885081149165;11505.3096814191;11507.712307192303;11519.712007199845;11168.776624467506;11242.887571124265;11248.943755281252;11257.099432045457;11278.266912650652;11006.394648294341;11085.334879907232;11085.390303533302;11086.61196858114;11094.334339939629;45967.77016114932;46658.76670616625;46710.5328946713;46711.766441167914;46758;3556.626554211822;3639.2721455708966;3712.461693054492;3727.7576957497677;3872.7289089763726;34489.13777155566;34547.13632159191;34737.82631086828;34920.476192857124;35200.29599407998;4630.536946305376;4679.719216847;4691.906161876744;4713.410823647044;4720.929186062212;361325;365454;366375.6812159381;366580;367406;64653;65082;65180.348196518375;65302;65394;39960.60039399627;40717.59282407197;41151.7942410292;43863.56136438659;68894.65552672204;18681.43955681332;18873;19062.237510499424;19106.2357505701;19108.044597770335;12075.879241207651;12202.816957745641;12502.687432814253;14641.34113964874;15003.17482538449;20788.1684732612;20984.370468885958;21015.369538913856;21083.15667373288;21090.894545527175;7464.514806537558;7552.169261381244;7572.167061623217;7637.389008879276;7669.463137580316;1010.540204207084;1059.7244716373798;1074.4896174317137;1093.3986307530827;1142.160512023662;34605.65394346075;34633.134171645404;34669.30661386758;34828.33776569151;35065;1115.7656892052598;1160.5995931403652;1189.8750631183775;1197.634721409961;1217.4643157010887;18163.45509634713;18168.818311816714;18185.272589096287;18270.360537381126;18333.266669333345;573511;577799;578820;579225;581880;65452.672736636014;65697.67151164214;66326.66836665786;66493.667531663;66506.33493665002;1312.311036705737;1379.544750232421;1386.4107754204422;1458.8249410070648;1468.8090548228874;30442.238944026576;35079;35186.648133518334;37900.94105993066;38077;60509.39490605037;61387.69306153441;61416.38583614107;61465.69267153636;61604.69197653983;437097;443105;443308;443856;446803;27789.583156253077;28611.570826437204;29416.55875161917;31101.222469438446;32356.191095222806;51729.482705172464;51750.741246293524;52268.47731522636;52300.7384963088;52533;42707;44244;44749.32876006928;45726;45823.77088114538;45072.77463612661;45337.773311133235;46028.30957535707;46117.25603172519;46533.302000469346;43244.13511729811;44235.11529769451;44237.11525769531;44451.11097778091;44454.55545444504;35966.10084747902;39082;39143.72783134313;39736;39965.40051899166;29000.419991600473;29017.30069302471;29073.12780616585;29161.562576560944;29393.412131757672 09:43:27 INFO - PROCESS | 18218 | |2;jquery.html;393.48453525881087;395.2076087444673;395.78429755783094;403.72748394833496;431.8035293941257;13493.055486115976;19289.517762055955;19572.31496897609;19911.800881991177;19915.103820328084;15759.763603545956;16892.324307027735;19071.809281907175;19211.423657290303;19561.804381956175;5785.537157027438;6038.909416358758;6045.516358691294;6046.062860256668;6046.213992181032;5766.7404966776385;5851.4148585141575;5960.0761881908375;5960.582759206857;5972.163897054415;5927.140564618127;5966.194563733906;5967.373425790294;5971.313298970609;5971.85070373242;5608.943910560883;5829.533637309048;5868.383819698913;5956.642601443928;5957.553183511232;5761.3950535194035;5789;5795.507381872546;5798.275215598051;5800.8839823203725;13964.162150271017;17863.821361786348;19258.518537036547;23311;23449.648255276184;2296.2307626944994;2371.6679664846934;2498.612715029173;2591.028364363364;2841.9005334813387;10253.43606101664;10258.589656413735;10495.73760655982;10904.454777261106;10965.61620343284;1952.687569988795;1997.2510308634262;2001.269536619138;2025.230412443277;2054.753429588459;77916.61041694813;83583.5820820892;83584.16415835885;88560;90234.5488272561;9946.403215807075;10325.32885362449;10568.577256909792;10627.787444251071;10647.254692171473;14281.571552853224;14345.354459049368;14349;14349.928250358891;14350.282485875665;7323.670434830446;7800.414968877357;8284.958575207085;8366.539840308842;8448.57757112142;7718.421118416029;7797.259260370369;7856.292933635886;7898.091719452222;8119.9188008120345;5015.598752099896;5045.722485263345;5053.242013697901;5069.389264528771;5091.215546301849;2000.3898810862827;2027.8479114066208;2135.508832968395;2140.657494800824;2143.1427429028204;976.8446132082524;995.1740055753708;1064.8615679961556;1088.7713580148259;1089.4607169451172;18709.345172919155;19296.4211073668;19627.60744785125;19736.40790776279;19980.10089545974;998.9700308990742;1021;1052.8526006359116;1053;1078.9946050269698;10049.648262310784;10737.624183153552;10765.515551800188;10806.783864322826;10857.022867941923;505265;516872;520288;521252;538633;94646.5267673671;95160;97649;97822;99750;460.89629833287245;478.7797613098014;479.5875547029552;483.55512928106225;487.4272729542844;10795.136389089012;11042.834357484484;11428.314301141958;11492.080633549464;11829.17195796286;41287.380689289086;41370.1725965485;41980.160396792504;42909.356359654004;43683;45199;61396.38603613907;61442.385576143664;61625.69187154035;61783;8904.332175087024;9157.954210228907;9377.671781487616;9412.435253884789;9457.810843783223;12370.381480926098;13519.594412167651;13846.65383365424;13939.721205575628;14057.718845623234;11404.372759498307;11542.769144617228;12179.939100304442;12290.631281061582;12446.733131742578;11921.94039029834;12028.15802893789;12176.939115304722;12191.878081219074;12567.87432125667;12366;12400.689982750142;12469.376531173586;12646.747065058464;12965.888186834904;9174.954125229331;9242.306826987868;9261.76845578839;9321.940738519745;9413.670521531714;10773.89226107729;11916.582919597773;11991.880081199426;12110.697232569257;12407.379631018593 09:43:27 INFO - PROCESS | 18218 | |3;mootools.html;1495.7232911911294;1545.2428310128034;1662.1107707376557;1837.173272027588;1852.1942954814665;8179.345652347813;8760.912390876088;8761.824763504726;8762.34282428818;8766.956165219173;7526.962365188167;7725.150233474314;8193.672253109884;8446.113158118402;8602.698905538307;2282.224043825099;2298.4139044543617;2302.4474126209757;2308.4113551044543;2314.0396735354807;2370.2178281167257;2371.5494056129364;2404.9759502405013;2407.5425669122897;2411.6623672685837;2107.9683804742863;2161.7189765330563;2164.6969424280614;2184.071769497965;2313.9652905206435;2392.1627430399394;2425.8787060647073;2427.599446091393;2430.1251549442186;2435.0990133650575;2337.2871274261342;2386.2125498585424;2407.6388541718707;2411.493586346869;2413.481101563172;8587.570621468902;8634.481931084156;8659.567021648892;8665.220130188314;8665.39342246043;5088.949110508885;5283.815066472694;5294.152935530296;5301.098813201726;5301.602379821529;3225.0808519572042;3294.637589865113;3346.247094403764;3357.244619960514;3361.512580675813;1119.2221406122749;1160.373398364882;1168.9532418703327;1179.64020973603;1195.6413076077229;11528.769424611544;11544.249623774513;11638.48699669046;11646.068314534818;11660.533578656843;4431.7562534060935;4486.439195100613;4605.585497305192;4694.826293426643;4703.553162449562;9866.80266394668;9896.257780666334;9904.950475247577;9925.156361709336;9925.50372481373;5257.0274499217;5262.473752624707;5273.736313184326;5280.260763493114;5283.286756287929;4744.122337367598;4808.422989241313;4836.516348365135;4844.467108618049;4846.418429788448;3980.920381592352;4024.0744628735556;4098.426220329156;4098.856540021085;4144.109016561454;1270.339423499775;1283.076185146693;1286.163993404276;1298.7402519496138;1312.5471712259255;895.8969718482458;1004.8593196952432;1016.588281745894;1018.8828284747349;1036.9792604147874;5789.132125571513;5791.638964843259;5819.388964158787;5963.73163207648;5967.671778052162;499.16888380845484;507.45955557331325;513.4788189987166;515.0445922813128;518.6473198225243;3875.399313106473;3940.8817735467983;3963.326234540135;3973.662238709684;4043.757374557536;9521.619135234512;9925.255605829447;9926.602935882629;9978;9994.650187243411;9555.331126821195;9576.904230957602;9708.805823883482;9725.124738773544;9757.073078057572;7513.2862378074;7532.133804612539;7591.962040189764;7592.582407967504;7597.620118994028;5472.890542189054;5610.635308704921;5706.457886500776;5721.9700453918695;5735.225744524436;9199.758032665504;9239.168474837297;9355.158035776813;9360;9373;9182.17360437564;9440.575174117044;9494.240460762985;9500.42997420157;9507.286953478655;7230.204677485578;7318.743843965436;7322.926770732224;7348.412127029718;7359.926400735924;7855.921440785519;7867.449278550446;7945.0863150738405;7947.125816160102;7950.045994480468;7287.089113860768;7305.634718264172;7321.487495875451;7504.474686771873;7512.962435187789;7102.609356485443;7106.111736032996;7249.492535522463;7262.96368518154;7268.200497945328;6718.563293385914;6838.281980391987;6847.828804279933;6863.862722745617;6868.450523957971;5000.624953128603;5072.82245121419;5119.654962411246;5205.219217117463;5218;4753.786079626356;4791.137595232753;4959.578435832995;4977.0792403405485;4982.701037937591 09:43:27 INFO - PROCESS | 18218 | |4;prototype.html;290.638155496407;312.1182658988357;317.57920754999617;324.47921086655913;327.69360647794315;5303.496167864057;5397.919031214535;5522.779088836452;5567.49892509674;5577.274954255946;5226.163813789794;5429.755660995254;5438.265834112403;5457.699826509541;5463.180522921563;4279.465066866642;4281.143771245747;4281.935770963438;4288.9571104289025;4296.205202037625;4091.4043523026044;4137.648299894513;4219.11398606293;4266.274733295348;4280.5291417944;4211.7262377945335;4226.852060177895;4230.17511585241;4235.915281694364;4273.67947403946;4258.574142585748;4268.210381079494;4271.337942618899;4278.315469524892;4295.162443323553;3650.1604630935035;3662.7802331860175;3681.650243226889;3701.907452313687;3783.5838057813617;5299.788008479656;5307.495787900143;5321.255024296563;5327.866803329948;5339.41266460689;4826.396700412449;4827.155247831615;4838.6854854434705;4844.394450693663;4847.321375007502;5448.727563621803;5609.747561359749;5620.353659329188;5642.4357564243655;5748.942510574882;4073.735207211522;4073.8370465181356;4085.407615895708;4090.652294554937;4098.585987834193;5912.083627037816;5919.822405327804;5935.821925342247;5939.465448109648;5948.464638182541;3877.2051729395407;4092.810346464627;4119.773412462283;4131.5455299916985;4179.728317659343;4157.272477316457;4160.355144952538;4164.541900390955;4172.937405938914;4179.474491810528;4542.364069030338;4587.770611469413;4650.930236046462;4717.976410117927;4740.478547359788;5706.914396284059;5711.8857622848145;5720.942790572124;5722.084466485342;5731.713414329267;1416.4617445370693;1439.3235179465635;1444.8988570800154;1448.478547722819;1454.7599646058493;925.2835989239863;972.4651441707174;979.9853002204973;984.6652138273022;986.1322036607883;5512.586556008316;5544.944550554443;5557.41647127054;5558.221848941152;5582.525485333711;5832.766689332464;5834.183214349994;5838.328592211865;5848.824535263949;5849.473547380671;5338.412774594792;5479.479449452296;5513.972430137824;5519.3652729936575;5522.502974732213;3078.9384212316077;3079.707427794356;3084.4602194615854;3096.0866544369464;3107.7203051725;5857.4435428634215;5987.550933679991;6021.337652858182;6042.667653279024;6047.818565443044;5846.503047240947;5900.262467191601;5919.911201331984;5930.318013428425;5947.613405128739;5985.713071689607;5986.2517185351835;6005.879882402415;6017.669028203491;6024.698765061729;5536.972315138399;5764.221830052889;5788.565857560616;5814.197598744403;5825.94174058271;5721.1990321354215;5748.798792042258;5755.942440575541;5781.710914454345;5785.56608254391;5847.970760146172;6021.488173505301;6026.337102918587;6071.453569178795;6076.756929722762;4130.504339479282;4134.689898257582;4140.109876376593;4147.129102888427;4156.688248381444;5702.8289151325525;5729.713514324184;5800.941990580209;5836.737346819488;5864.179014937998;5791.681457519877;5796.4929118430355;5798.304203495608;5800.216970708901;5817.476427121579;5579.74891129892;5805.3614102447855;5819.738111784895;5824;5844.211031510692;5676.17695434168;5688.800891968761;5705.543556515386;5707.514861236841;5729.4843464088435;5778.451047150514;5779.566532510163;5797.246357973437;5816.621919575383;5825.475707186373;5474.890502190014;5668.688222147656;5677.858053548694;5723.914141287967;5748.051571490657 09:43:27 INFO - PROCESS | 18218 | |5;yui.html;813.2396209544075;820.3027426687316;849.5115308697499;855.2687452228345;918.9248579162379;15152.242387880604;15872.84127158728;16038.117903515302;16071.598210044755;16120.919395403022;15985.200739962984;16007.03957762535;16260.105694186816;16364.83635163651;16393.590160246;852.752701716503;872.2890843962182;873.4890089297768;873.921347078766;874.9475031498099;726.2809818279891;739.693027393631;860.6213266162869;868.1448772958631;874.7200895713374;915.4141349536269;921.4885738415177;921.6728061538158;922.2253307221932;922.5479515037616;923.1138107416855;923.1368670293298;923.3075193604797;923.6305477808863;924.3852837862813;549.2941570082425;561.3292115921475;562.15115176084;566.108379302596;658.0885473619072;14112.294385280695;14594.635134121625;14609;14628.853711462856;14661.70676586473;15343.232838358037;15571.37714491419;15848.128352940583;16103.83896161035;16160.838391616051;13649.931750341284;13650.385732642055;13723.931380343134;13929.721405571932;13930.58208253744;4611.884702882421;4615.100055489181;4665.300204969248;4716.811327546894;4746.95253047472;84169;84336;84598.15401845964;85408;85518.57240713756;12015.640955393459;12054.638360849189;12277.324747138815;12588.4335204916;12824.358782060859;23991.52016959651;24021.639675404884;24046.87976560141;24048.759512404653;24065.157719479736;9320.580573874191;9395.154436100647;9465.337426380087;9477;9742.366746161477;5246.921296180561;5397.271368365299;5444.156155795858;5534.0315444797;5592.888142237132;3365.932681346408;3449.189440481485;3450.0339904826687;3470.5661792275964;3486.511888335635;546.7621584610628;554.3154204557369;568.0258356917906;569.5927411900511;573.7647564498535;648.2447948140458;665.8734840380313;711.9359257666835;718.6155406857306;729.5695539631643;9141.131592498701;9250.629974800933;9329.906700932905;9612.711618651452;9947;529.9417064122944;563.9605227634027;577.1112486770411;596.3380647481292;668.2181847238743;4474.060447306102;4582.060677561092;4647.117047760915;4662.557057079572;4677.298405239241;76300.61849690716;77095;77680;130609.34695326653;132082.33958830335;100173.99826001792;113995.43002285101;115707.96570771509;117367;122076;100423.49788251011;101077;101089;105712;106131.4693426528;8480.533570653673;8541.786455338417;8565.657373705231;8738.781530461534;8776.452788118931;58173.4182658168;58452;59314.40685593089;59778.804423912145;60105.69947150236;83877;87352;89412.5529372349;90409.54795225982;90413;12381.504739810307;12551.497940082294;12560.685982850136;12924.806127907901;13049.151805132637;14467.85532144665;14591.343389547283;14609;14920.925395372953;15102.924485377504;14976.47582334613;15016.549503514912;15801.525954221392;16430.424935127212;16533.83466165323;14940;14998.850011500183;15226.162561059247;15781;16158.596035098746;13921.651958701113;14475;14652.560423187322;14945.626359341104;15380.230988450756;10266.435346056038;10655.57377704883;10851.782964340511;11576.24754390962;11579.13156513248;11010;11361.147913906325;11535.480903359201;11619.360935148648;11923.523059077539 09:43:27 INFO - PROCESS | 18218 | __end_tp_report 09:43:27 INFO - PROCESS | 18218 | __start_cc_report 09:43:27 INFO - PROCESS | 18218 | _x_x_mozilla_cycle_collect,909 09:43:27 INFO - PROCESS | 18218 | __end_cc_report 09:43:27 INFO - PROCESS | 18218 | __startTimestamp1476636207445__endTimestamp 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | ------- Summary: start ------- 09:43:27 INFO - PROCESS | 18218 | Number of tests: 6 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#0] dojo.html Cycles:165 Average:5487.98 Median:5684.91 stddev:622.50 (11.0%) stddev-sans-first:594.80 09:43:27 INFO - PROCESS | 18218 | Values: 3069.7 3095.6 3128.1 3135.6 3173.0 5444.3 5494.4 5510.8 5525.4 5533.4 5567.4 5617.0 5620.9 5622.3 5623.0 5069.3 5080.7 5098.5 5125.8 5185.6 4966.2 4989.2 5118.7 5162.8 5167.4 4960.1 4973.8 5012.8 5015.4 5051.2 5067.2 5083.5 5149.7 5152.7 5186.9 4797.4 5034.9 5058.1 5100.6 5121.7 5374.3 5378.6 5386.4 5422.1 5488.8 5815.4 5825.5 5931.0 5932.0 5932.9 5770.8 5959.3 5969.6 5980.6 5981.3 5413.3 5461.2 5507.3 5521.2 5571.0 6075.0 6108.2 6110.5 6111.1 6112.4 5589.0 5842.9 5844.9 5845.6 5847.7 5698.5 5823.6 5851.9 5857.7 5918.1 5864.5 5865.6 5873.4 5875.3 5876.8 5813.1 5878.9 5883.4 5883.8 5900.0 4422.6 4458.5 4475.2 4513.5 4517.5 4034.8 4035.1 4041.8 4048.6 4066.3 5614.8 5619.3 5621.3 5626.5 5659.1 5745.1 5797.9 5817.5 5836.0 5924.1 5650.2 5654.7 5657.8 5668.0 5684.9 5157.8 5167.6 5170.2 5170.9 5193.0 5954.7 5957.4 5960.4 6059.5 6066.7 5831.8 5862.1 5912.3 5945.3 5951.7 6085.1 6092.5 6094.5 6095.5 6101.0 5618.7 5642.4 5741.4 5793.6 5794.6 5880.0 5890.2 5936.6 5971.1 6029.8 5791.2 5838.1 5846.8 5849.8 5856.2 5648.2 5815.4 5833.4 5833.9 5836.0 5643.7 5683.3 5714.1 5717.8 5812.4 5818.3 5862.9 5864.4 5871.7 5880.9 5531.3 5576.1 5722.7 5741.1 5741.4 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#1] ext.html Cycles:180 Average:63985.48 Median:21477.36 stddev:124605.36 (580.2%) stddev-sans-first:124864.01 09:43:27 INFO - PROCESS | 18218 | Values: 592.8 607.3 632.2 657.0 660.5 35227.5 47378.3 48178.3 48211.3 52560.5 49641.0 49945.0 50116.0 50177.0 50240.5 8387.8 9238.6 9388.8 9400.1 9400.2 8295.3 10854.1 11155.8 11160.9 11671.9 11429.2 11491.9 11505.3 11507.7 11519.7 11168.8 11242.9 11248.9 11257.1 11278.3 11006.4 11085.3 11085.4 11086.6 11094.3 45967.8 46658.8 46710.5 46711.8 46758.0 3556.6 3639.3 3712.5 3727.8 3872.7 34489.1 34547.1 34737.8 34920.5 35200.3 4630.5 4679.7 4691.9 4713.4 4720.9 361325.0 365454.0 366375.7 366580.0 367406.0 64653.0 65082.0 65180.3 65302.0 65394.0 39960.6 40717.6 41151.8 43863.6 68894.7 18681.4 18873.0 19062.2 19106.2 19108.0 12075.9 12202.8 12502.7 14641.3 15003.2 20788.2 20984.4 21015.4 21083.2 21090.9 7464.5 7552.2 7572.2 7637.4 7669.5 1010.5 1059.7 1074.5 1093.4 1142.2 34605.7 34633.1 34669.3 34828.3 35065.0 1115.8 1160.6 1189.9 1197.6 1217.5 18163.5 18168.8 18185.3 18270.4 18333.3 573511.0 577799.0 578820.0 579225.0 581880.0 65452.7 65697.7 66326.7 66493.7 66506.3 1312.3 1379.5 1386.4 1458.8 1468.8 30442.2 35079.0 35186.6 37900.9 38077.0 60509.4 61387.7 61416.4 61465.7 61604.7 437097.0 443105.0 443308.0 443856.0 446803.0 27789.6 28611.6 29416.6 31101.2 32356.2 51729.5 51750.7 52268.5 52300.7 52533.0 42707.0 44244.0 44749.3 45726.0 45823.8 45072.8 45337.8 46028.3 46117.3 46533.3 43244.1 44235.1 44237.1 44451.1 44454.6 35966.1 39082.0 39143.7 39736.0 39965.4 29000.4 29017.3 29073.1 29161.6 29393.4 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#2] jquery.html Cycles:180 Average:29898.57 Median:1617.69 stddev:85884.89 (5309.1%) stddev-sans-first:86097.25 09:43:27 INFO - PROCESS | 18218 | Values: 393.5 395.2 395.8 403.7 431.8 13493.1 19289.5 19572.3 19911.8 19915.1 15759.8 16892.3 19071.8 19211.4 19561.8 5785.5 6038.9 6045.5 6046.1 6046.2 5766.7 5851.4 5960.1 5960.6 5972.2 5927.1 5966.2 5967.4 5971.3 5971.9 5608.9 5829.5 5868.4 5956.6 5957.6 5761.4 5789.0 5795.5 5798.3 5800.9 13964.2 17863.8 19258.5 23311.0 23449.6 2296.2 2371.7 2498.6 2591.0 2841.9 10253.4 10258.6 10495.7 10904.5 10965.6 1952.7 1997.3 2001.3 2025.2 2054.8 77916.6 83583.6 83584.2 88560.0 90234.5 9946.4 10325.3 10568.6 10627.8 10647.3 14281.6 14345.4 14349.0 14349.9 14350.3 7323.7 7800.4 8285.0 8366.5 8448.6 7718.4 7797.3 7856.3 7898.1 8119.9 5015.6 5045.7 5053.2 5069.4 5091.2 2000.4 2027.8 2135.5 2140.7 2143.1 976.8 995.2 1064.9 1088.8 1089.5 18709.3 19296.4 19627.6 19736.4 19980.1 999.0 1021.0 1052.9 1053.0 1079.0 10049.6 10737.6 10765.5 10806.8 10857.0 505265.0 516872.0 520288.0 521252.0 538633.0 94646.5 95160.0 97649.0 97822.0 99750.0 460.9 478.8 479.6 483.6 487.4 10795.1 11042.8 11428.3 11492.1 11829.2 41287.4 41370.2 41980.2 42909.4 43683.0 45199.0 61396.4 61442.4 61625.7 61783.0 8904.3 9158.0 9377.7 9412.4 9457.8 12370.4 13519.6 13846.7 13939.7 14057.7 11404.4 11542.8 12179.9 12290.6 12446.7 11921.9 12028.2 12176.9 12191.9 12567.9 12366.0 12400.7 12469.4 12646.7 12965.9 9175.0 9242.3 9261.8 9321.9 9413.7 10773.9 11916.6 11991.9 12110.7 12407.4 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#3] mootools.html Cycles:180 Average:5541.71 Median:5259.75 stddev:3031.56 (57.6%) stddev-sans-first:3024.82 09:43:27 INFO - PROCESS | 18218 | Values: 1495.7 1545.2 1662.1 1837.2 1852.2 8179.3 8760.9 8761.8 8762.3 8767.0 7527.0 7725.2 8193.7 8446.1 8602.7 2282.2 2298.4 2302.4 2308.4 2314.0 2370.2 2371.5 2405.0 2407.5 2411.7 2108.0 2161.7 2164.7 2184.1 2314.0 2392.2 2425.9 2427.6 2430.1 2435.1 2337.3 2386.2 2407.6 2411.5 2413.5 8587.6 8634.5 8659.6 8665.2 8665.4 5088.9 5283.8 5294.2 5301.1 5301.6 3225.1 3294.6 3346.2 3357.2 3361.5 1119.2 1160.4 1169.0 1179.6 1195.6 11528.8 11544.2 11638.5 11646.1 11660.5 4431.8 4486.4 4605.6 4694.8 4703.6 9866.8 9896.3 9905.0 9925.2 9925.5 5257.0 5262.5 5273.7 5280.3 5283.3 4744.1 4808.4 4836.5 4844.5 4846.4 3980.9 4024.1 4098.4 4098.9 4144.1 1270.3 1283.1 1286.2 1298.7 1312.5 895.9 1004.9 1016.6 1018.9 1037.0 5789.1 5791.6 5819.4 5963.7 5967.7 499.2 507.5 513.5 515.0 518.6 3875.4 3940.9 3963.3 3973.7 4043.8 9521.6 9925.3 9926.6 9978.0 9994.7 9555.3 9576.9 9708.8 9725.1 9757.1 7513.3 7532.1 7592.0 7592.6 7597.6 5472.9 5610.6 5706.5 5722.0 5735.2 9199.8 9239.2 9355.2 9360.0 9373.0 9182.2 9440.6 9494.2 9500.4 9507.3 7230.2 7318.7 7322.9 7348.4 7359.9 7855.9 7867.4 7945.1 7947.1 7950.0 7287.1 7305.6 7321.5 7504.5 7513.0 7102.6 7106.1 7249.5 7263.0 7268.2 6718.6 6838.3 6847.8 6863.9 6868.5 5000.6 5072.8 5119.7 5205.2 5218.0 4753.8 4791.1 4959.6 4977.1 4982.7 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#4] prototype.html Cycles:180 Average:4798.03 Median:5529.88 stddev:1420.75 (25.7%) stddev-sans-first:1383.87 09:43:27 INFO - PROCESS | 18218 | Values: 290.6 312.1 317.6 324.5 327.7 5303.5 5397.9 5522.8 5567.5 5577.3 5226.2 5429.8 5438.3 5457.7 5463.2 4279.5 4281.1 4281.9 4289.0 4296.2 4091.4 4137.6 4219.1 4266.3 4280.5 4211.7 4226.9 4230.2 4235.9 4273.7 4258.6 4268.2 4271.3 4278.3 4295.2 3650.2 3662.8 3681.7 3701.9 3783.6 5299.8 5307.5 5321.3 5327.9 5339.4 4826.4 4827.2 4838.7 4844.4 4847.3 5448.7 5609.7 5620.4 5642.4 5748.9 4073.7 4073.8 4085.4 4090.7 4098.6 5912.1 5919.8 5935.8 5939.5 5948.5 3877.2 4092.8 4119.8 4131.5 4179.7 4157.3 4160.4 4164.5 4172.9 4179.5 4542.4 4587.8 4650.9 4718.0 4740.5 5706.9 5711.9 5720.9 5722.1 5731.7 1416.5 1439.3 1444.9 1448.5 1454.8 925.3 972.5 980.0 984.7 986.1 5512.6 5544.9 5557.4 5558.2 5582.5 5832.8 5834.2 5838.3 5848.8 5849.5 5338.4 5479.5 5514.0 5519.4 5522.5 3078.9 3079.7 3084.5 3096.1 3107.7 5857.4 5987.6 6021.3 6042.7 6047.8 5846.5 5900.3 5919.9 5930.3 5947.6 5985.7 5986.3 6005.9 6017.7 6024.7 5537.0 5764.2 5788.6 5814.2 5825.9 5721.2 5748.8 5755.9 5781.7 5785.6 5848.0 6021.5 6026.3 6071.5 6076.8 4130.5 4134.7 4140.1 4147.1 4156.7 5702.8 5729.7 5800.9 5836.7 5864.2 5791.7 5796.5 5798.3 5800.2 5817.5 5579.7 5805.4 5819.7 5824.0 5844.2 5676.2 5688.8 5705.5 5707.5 5729.5 5778.5 5779.6 5797.2 5816.6 5825.5 5474.9 5668.7 5677.9 5723.9 5748.1 09:43:27 INFO - PROCESS | 18218 | 09:43:27 INFO - PROCESS | 18218 | [#5] yui.html Cycles:180 Average:22460.95 Median:4631.11 stddev:32570.27 (703.3%) stddev-sans-first:32621.08 09:43:27 INFO - PROCESS | 18218 | Values: 813.2 820.3 849.5 855.3 918.9 15152.2 15872.8 16038.1 16071.6 16120.9 15985.2 16007.0 16260.1 16364.8 16393.6 852.8 872.3 873.5 873.9 874.9 726.3 739.7 860.6 868.1 874.7 915.4 921.5 921.7 922.2 922.5 923.1 923.1 923.3 923.6 924.4 549.3 561.3 562.2 566.1 658.1 14112.3 14594.6 14609.0 14628.9 14661.7 15343.2 15571.4 15848.1 16103.8 16160.8 13649.9 13650.4 13723.9 13929.7 13930.6 4611.9 4615.1 4665.3 4716.8 4747.0 84169.0 84336.0 84598.2 85408.0 85518.6 12015.6 12054.6 12277.3 12588.4 12824.4 23991.5 24021.6 24046.9 24048.8 24065.2 9320.6 9395.2 9465.3 9477.0 9742.4 5246.9 5397.3 5444.2 5534.0 5592.9 3365.9 3449.2 3450.0 3470.6 3486.5 546.8 554.3 568.0 569.6 573.8 648.2 665.9 711.9 718.6 729.6 9141.1 9250.6 9329.9 9612.7 9947.0 529.9 564.0 577.1 596.3 668.2 4474.1 4582.1 4647.1 4662.6 4677.3 76300.6 77095.0 77680.0 130609.3 132082.3 100174.0 113995.4 115708.0 117367.0 122076.0 100423.5 101077.0 101089.0 105712.0 106131.5 8480.5 8541.8 8565.7 8738.8 8776.5 58173.4 58452.0 59314.4 59778.8 60105.7 83877.0 87352.0 89412.6 90409.5 90413.0 12381.5 12551.5 12560.7 12924.8 13049.2 14467.9 14591.3 14609.0 14920.9 15102.9 14976.5 15016.5 15801.5 16430.4 16533.8 14940.0 14998.9 15226.2 15781.0 16158.6 13921.7 14475.0 14652.6 14945.6 15380.2 10266.4 10655.6 10851.8 11576.2 11579.1 11010.0 11361.1 11535.5 11619.4 11923.5 09:43:27 INFO - PROCESS | 18218 | -------- Summary: end -------- 09:43:27 INFO - PROCESS | 18218 | 09:43:28 INFO - TEST-INFO | 18218: exit 0 09:43:28 INFO - TEST-OK | dromaeo_css | took 1113287ms 09:43:28 INFO - TEST-START | kraken 09:43:28 INFO - operating with platform_type : linux_ 09:43:28 INFO - Initialising browser for kraken test... 09:43:28 INFO - TEST-INFO | started process 18536 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUj1Gx3/profile http://localhost:56314/getInfo.html) 09:43:31 INFO - PROCESS | 18536 | __metrics Screen width/height:1600/1200 09:43:31 INFO - PROCESS | 18536 | colorDepth:24 09:43:31 INFO - PROCESS | 18536 | Browser inner width/height: 1024/697 09:43:31 INFO - PROCESS | 18536 | __metrics 09:43:32 INFO - PROCESS | 18536 | 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" 09:43:32 INFO - PROCESS | 18536 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:43:32 INFO - PROCESS | 18536 | console.error: PushService: 09:43:32 INFO - PROCESS | 18536 | stateChangeProcessEnqueue: Error transitioning state 09:43:32 INFO - PROCESS | 18536 | UnknownError 09:43:33 INFO - TEST-INFO | 18536: exit 0 09:43:33 INFO - Browser initialized. 09:43:33 INFO - Running cycle 1/1 for kraken test... 09:43:33 INFO - TEST-INFO | started process 18629 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUj1Gx3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:45 INFO - PROCESS | 18629 | RSS: Main: 145022976 09:43:45 INFO - PROCESS | 18629 | 09:43:47 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56314/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:48 INFO - PROCESS | 18629 | RSS: Main: 186101760 09:43:48 INFO - PROCESS | 18629 | 09:43:51 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56314/tests/kraken/driver.html?testName=audio-dft) 09:43:51 INFO - PROCESS | 18629 | RSS: Main: 175837184 09:43:51 INFO - PROCESS | 18629 | 09:43:54 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56314/tests/kraken/driver.html?testName=audio-fft) 09:43:55 INFO - PROCESS | 18629 | RSS: Main: 266874880 09:43:55 INFO - PROCESS | 18629 | 09:43:57 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56314/tests/kraken/driver.html?testName=audio-oscillator) 09:43:57 INFO - PROCESS | 18629 | RSS: Main: 173101056 09:43:57 INFO - PROCESS | 18629 | 09:43:59 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56314/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:00 INFO - PROCESS | 18629 | RSS: Main: 175263744 09:44:00 INFO - PROCESS | 18629 | 09:44:03 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56314/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:04 INFO - PROCESS | 18629 | RSS: Main: 211406848 09:44:04 INFO - PROCESS | 18629 | 09:44:07 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56314/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:07 INFO - PROCESS | 18629 | RSS: Main: 210370560 09:44:07 INFO - PROCESS | 18629 | 09:44:10 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56314/tests/kraken/driver.html?testName=json-parse-financial) 09:44:11 INFO - PROCESS | 18629 | RSS: Main: 210214912 09:44:11 INFO - PROCESS | 18629 | 09:44:12 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56314/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:13 INFO - PROCESS | 18629 | RSS: Main: 172056576 09:44:13 INFO - PROCESS | 18629 | 09:44:15 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:15 INFO - PROCESS | 18629 | RSS: Main: 178565120 09:44:15 INFO - PROCESS | 18629 | 09:44:17 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:18 INFO - PROCESS | 18629 | RSS: Main: 188637184 09:44:18 INFO - PROCESS | 18629 | 09:44:20 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:21 INFO - PROCESS | 18629 | RSS: Main: 196706304 09:44:21 INFO - PROCESS | 18629 | 09:44:24 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:24 INFO - PROCESS | 18629 | RSS: Main: 193585152 09:44:24 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | Cycle 1(1): loaded http://localhost:56314/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56314/tests/kraken/driver.html?testName=ai-astar) 09:44:26 INFO - PROCESS | 18629 | RSS: Main: 245039104 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | __start_tp_report 09:44:26 INFO - PROCESS | 18629 | _x_x_mozilla_page_load 09:44:26 INFO - PROCESS | 18629 | _x_x_mozilla_page_load_details 09:44:26 INFO - PROCESS | 18629 | |i|pagename|runs| 09:44:26 INFO - PROCESS | 18629 | |0;ai-astar;110;111;110;111;111;112;111;110;112;110 09:44:26 INFO - PROCESS | 18629 | |1;audio-beat-detection;137;120;118;155;117;124;117;117;146;122 09:44:26 INFO - PROCESS | 18629 | |2;audio-dft;161;160;161;163;159;160;159;160;159;161 09:44:26 INFO - PROCESS | 18629 | |3;audio-fft;75;75;75;107;74;75;75;74;95;75 09:44:26 INFO - PROCESS | 18629 | |4;audio-oscillator;82;81;120;81;81;81;99;81;81;109 09:44:26 INFO - PROCESS | 18629 | |5;imaging-gaussian-blur;116;116;117;116;117;118;117;117;117;117 09:44:26 INFO - PROCESS | 18629 | |6;imaging-darkroom;146;145;146;146;146;146;146;147;145;146 09:44:26 INFO - PROCESS | 18629 | |7;imaging-desaturate;96;94;95;95;95;95;96;95;96;95 09:44:26 INFO - PROCESS | 18629 | |8;json-parse-financial;50;49;49;50;49;49;49;50;49;49 09:44:26 INFO - PROCESS | 18629 | |9;json-stringify-tinderbox;60;60;61;60;60;60;59;59;60;60 09:44:26 INFO - PROCESS | 18629 | |10;stanford-crypto-aes;75;78;80;75;75;74;79;77;75;73 09:44:26 INFO - PROCESS | 18629 | |11;stanford-crypto-ccm;122;129;119;120;120;129;129;122;164;140 09:44:26 INFO - PROCESS | 18629 | |12;stanford-crypto-pbkdf2;155;158;151;151;154;154;152;152;152;152 09:44:26 INFO - PROCESS | 18629 | |13;stanford-crypto-sha256-iterative;62;62;64;62;63;62;64;62;61;60 09:44:26 INFO - PROCESS | 18629 | __end_tp_report 09:44:26 INFO - PROCESS | 18629 | __start_cc_report 09:44:26 INFO - PROCESS | 18629 | _x_x_mozilla_cycle_collect,1626 09:44:26 INFO - PROCESS | 18629 | __end_cc_report 09:44:26 INFO - PROCESS | 18629 | __startTimestamp1476636266619__endTimestamp 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | ------- Summary: start ------- 09:44:26 INFO - PROCESS | 18629 | Number of tests: 14 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 09:44:26 INFO - PROCESS | 18629 | Values: 110.0 111.0 110.0 111.0 111.0 112.0 111.0 110.0 112.0 110.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:123.00 stddev:13.78 (11.2%) stddev-sans-first:14.16 09:44:26 INFO - PROCESS | 18629 | Values: 137.0 120.0 118.0 155.0 117.0 124.0 117.0 117.0 146.0 122.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.25 (0.8%) stddev-sans-first:1.30 09:44:26 INFO - PROCESS | 18629 | Values: 161.0 160.0 161.0 163.0 159.0 160.0 159.0 160.0 159.0 161.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.00 stddev:11.43 (15.2%) stddev-sans-first:11.98 09:44:26 INFO - PROCESS | 18629 | Values: 75.0 75.0 75.0 107.0 74.0 75.0 75.0 74.0 95.0 75.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:14.49 (17.9%) stddev-sans-first:15.11 09:44:26 INFO - PROCESS | 18629 | Values: 82.0 81.0 120.0 81.0 81.0 81.0 99.0 81.0 81.0 109.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 09:44:26 INFO - PROCESS | 18629 | Values: 116.0 116.0 117.0 116.0 117.0 118.0 117.0 117.0 117.0 117.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 09:44:26 INFO - PROCESS | 18629 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 09:44:26 INFO - PROCESS | 18629 | Values: 96.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.00 stddev:0.48 (1.0%) stddev-sans-first:0.44 09:44:26 INFO - PROCESS | 18629 | Values: 50.0 49.0 49.0 50.0 49.0 49.0 49.0 50.0 49.0 49.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 09:44:26 INFO - PROCESS | 18629 | Values: 60.0 60.0 61.0 60.0 60.0 60.0 59.0 59.0 60.0 60.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:2.28 (3.0%) stddev-sans-first:2.39 09:44:26 INFO - PROCESS | 18629 | Values: 75.0 78.0 80.0 75.0 75.0 74.0 79.0 77.0 75.0 73.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:129.00 stddev:13.76 (10.7%) stddev-sans-first:14.33 09:44:26 INFO - PROCESS | 18629 | Values: 122.0 129.0 119.0 120.0 120.0 129.0 129.0 122.0 164.0 140.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.10 Median:153.00 stddev:2.18 (1.4%) stddev-sans-first:2.20 09:44:26 INFO - PROCESS | 18629 | Values: 155.0 158.0 151.0 151.0 154.0 154.0 152.0 152.0 152.0 152.0 09:44:26 INFO - PROCESS | 18629 | 09:44:26 INFO - PROCESS | 18629 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 09:44:26 INFO - PROCESS | 18629 | Values: 62.0 62.0 64.0 62.0 63.0 62.0 64.0 62.0 61.0 60.0 09:44:26 INFO - PROCESS | 18629 | -------- Summary: end -------- 09:44:26 INFO - PROCESS | 18629 | 09:44:27 INFO - TEST-INFO | 18629: exit 0 09:44:27 INFO - TEST-OK | kraken | took 59470ms 09:44:27 INFO - SUITE-END | took 1172s 09:44:27 INFO - Completed test suite (00:19:32) 09:44:27 INFO - javascript benchmark 09:44:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3069.69303069693, 3095.551145083962, 3128.077217220921, 3135.5923729915107, 3172.9687851448284, 5444.265024221729, 5494.36814766302, 5510.752016159267, 5525.4474552544725, 5533.363663178736, 5567.387587365387, 5617.045102332603, 5620.91568626471, 5622.32532096148, 5623.0, 5069.340985671857, 5080.669756465836, 5098.515641014116, 5125.820596279131, 5185.585153187736, 4966.230234313687, 4989.176785830334, 5118.692878427288, 5162.819301324435, 5167.354080739908, 4960.0823847588135, 4973.825916092937, 5012.8496145115705, 5015.423226328982, 5051.242313652963, 5067.214581739836, 5083.491650834905, 5149.665271757323, 5152.742362881841, 5186.948130518685, 4797.376341075673, 5034.924476132825, 5058.114829904789, 5100.566451851597, 5121.743912804345, 5374.301340825707, 5378.596605254583, 5386.380566234894, 5422.132458806612, 5488.807891723811, 5815.360310365856, 5825.504832089277, 5931.0, 5931.970340148272, 5932.911006334908, 5770.7691692332255, 5959.344472108065, 5969.611975221596, 5980.641161530322, 5981.342052374235, 5413.26920865682, 5461.208124821918, 5507.256520369739, 5521.171824226398, 5571.025070612626, 6075.0, 6108.205933228652, 6110.4500594945775, 6111.083337499411, 6112.419320164578, 5589.0219211637805, 5842.912356314659, 5844.8538786529825, 5845.561582881301, 5847.707614619252, 5698.487136157766, 5823.621464604787, 5851.853703657443, 5857.677827719516, 5918.112283157475, 5864.472197502245, 5865.589408741433, 5873.442023007808, 5875.265591801025, 5876.7649294028615, 5813.128030795415, 5878.853028674232, 5883.441073098134, 5883.764649414061, 5899.997000509924, 4422.624076953495, 4458.464984201916, 4475.216837053503, 4513.458384993822, 4517.503074661785, 4034.8386064557676, 4035.1324465240114, 4041.7574945503366, 4048.554658987509, 4066.2883995300704, 5614.775408983595, 5619.269494965628, 5621.325440947113, 5626.493615574536, 5659.122835960433, 5745.052066408995, 5797.9420205797405, 5817.476427121579, 5836.0, 5924.081767326072, 5650.180723795109, 5654.6889921054735, 5657.773689052474, 5668.0, 5684.914726279109, 5157.767900444489, 5167.638265321391, 5170.1727723564045, 5170.870728231749, 5193.0, 5954.7320370582565, 5957.404259574095, 5960.433758792908, 6059.545534084873, 6066.66633335162, 5831.825045248649, 5862.091375836581, 5912.349641539513, 5945.34601193877, 5951.7024148791525, 6085.1480792689945, 6092.543059270661, 6094.451499365078, 6095.542834287357, 6100.969495152496, 5618.747156377891, 5642.379338272704, 5741.425857414225, 5793.565482588907, 5794.594378393641, 5880.0, 5890.234269545103, 5936.5844390892225, 5971.134185543159, 6029.849253768691, 5791.218185545066, 5838.095095260242, 5846.824595262149, 5849.824505264849, 5856.238688970407, 5648.15277708347, 5815.389384114607, 5833.445822646841, 5833.883322333614, 5836.037053886062, 5643.71781410936, 5683.346415162309, 5714.114312281603, 5717.799877004285, 5812.418758124154, 5818.272715910512, 5862.853428664317, 5864.413558644102, 5871.6770577618645, 5880.852978675396, 5531.280933478622, 5576.0799468088935, 5722.74247658848, 5741.0527263002805, 5741.3971532988435], "value": 5445.250802420245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7688201601376, 607.2803727582814, 632.1623848400868, 657.0407205479999, 660.4650233311021, 35227.47158792617, 47378.289325660146, 48178.27732584014, 48211.276830847564, 52560.47439525604, 49641.0, 49945.0, 50116.0, 50177.0, 50240.49759502404, 8387.790305242357, 9238.584263708133, 9388.765280867965, 9400.106989835955, 9400.24798016157, 8295.253427191552, 10854.131669466424, 11155.776884462306, 11160.888391116107, 11671.941640291787, 11429.199956003084, 11491.885081149165, 11505.3096814191, 11507.712307192303, 11519.712007199845, 11168.776624467506, 11242.887571124265, 11248.943755281252, 11257.099432045457, 11278.266912650652, 11006.394648294341, 11085.334879907232, 11085.390303533302, 11086.61196858114, 11094.334339939629, 45967.77016114932, 46658.76670616625, 46710.5328946713, 46711.766441167914, 46758.0, 3556.626554211822, 3639.2721455708966, 3712.461693054492, 3727.7576957497677, 3872.7289089763726, 34489.13777155566, 34547.13632159191, 34737.82631086828, 34920.476192857124, 35200.29599407998, 4630.536946305376, 4679.719216847, 4691.906161876744, 4713.410823647044, 4720.929186062212, 361325.0, 365454.0, 366375.6812159381, 366580.0, 367406.0, 64653.0, 65082.0, 65180.348196518375, 65302.0, 65394.0, 39960.60039399627, 40717.59282407197, 41151.7942410292, 43863.56136438659, 68894.65552672204, 18681.43955681332, 18873.0, 19062.237510499424, 19106.2357505701, 19108.044597770335, 12075.879241207651, 12202.816957745641, 12502.687432814253, 14641.34113964874, 15003.17482538449, 20788.1684732612, 20984.370468885958, 21015.369538913856, 21083.15667373288, 21090.894545527175, 7464.514806537558, 7552.169261381244, 7572.167061623217, 7637.389008879276, 7669.463137580316, 1010.540204207084, 1059.7244716373798, 1074.4896174317137, 1093.3986307530827, 1142.160512023662, 34605.65394346075, 34633.134171645404, 34669.30661386758, 34828.33776569151, 35065.0, 1115.7656892052598, 1160.5995931403652, 1189.8750631183775, 1197.634721409961, 1217.4643157010887, 18163.45509634713, 18168.818311816714, 18185.272589096287, 18270.360537381126, 18333.266669333345, 573511.0, 577799.0, 578820.0, 579225.0, 581880.0, 65452.672736636014, 65697.67151164214, 66326.66836665786, 66493.667531663, 66506.33493665002, 1312.311036705737, 1379.544750232421, 1386.4107754204422, 1458.8249410070648, 1468.8090548228874, 30442.238944026576, 35079.0, 35186.648133518334, 37900.94105993066, 38077.0, 60509.39490605037, 61387.69306153441, 61416.38583614107, 61465.69267153636, 61604.69197653983, 437097.0, 443105.0, 443308.0, 443856.0, 446803.0, 27789.583156253077, 28611.570826437204, 29416.55875161917, 31101.222469438446, 32356.191095222806, 51729.482705172464, 51750.741246293524, 52268.47731522636, 52300.7384963088, 52533.0, 42707.0, 44244.0, 44749.32876006928, 45726.0, 45823.77088114538, 45072.77463612661, 45337.773311133235, 46028.30957535707, 46117.25603172519, 46533.302000469346, 43244.13511729811, 44235.11529769451, 44237.11525769531, 44451.11097778091, 44454.55545444504, 35966.10084747902, 39082.0, 39143.72783134313, 39736.0, 39965.40051899166, 29000.419991600473, 29017.30069302471, 29073.12780616585, 29161.562576560944, 29393.412131757672], "value": 21855.7749832125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.48453525881087, 395.2076087444673, 395.78429755783094, 403.72748394833496, 431.8035293941257, 13493.055486115976, 19289.517762055955, 19572.31496897609, 19911.800881991177, 19915.103820328084, 15759.763603545956, 16892.324307027735, 19071.809281907175, 19211.423657290303, 19561.804381956175, 5785.537157027438, 6038.909416358758, 6045.516358691294, 6046.062860256668, 6046.213992181032, 5766.7404966776385, 5851.4148585141575, 5960.0761881908375, 5960.582759206857, 5972.163897054415, 5927.140564618127, 5966.194563733906, 5967.373425790294, 5971.313298970609, 5971.85070373242, 5608.943910560883, 5829.533637309048, 5868.383819698913, 5956.642601443928, 5957.553183511232, 5761.3950535194035, 5789.0, 5795.507381872546, 5798.275215598051, 5800.8839823203725, 13964.162150271017, 17863.821361786348, 19258.518537036547, 23311.0, 23449.648255276184, 2296.2307626944994, 2371.6679664846934, 2498.612715029173, 2591.028364363364, 2841.9005334813387, 10253.43606101664, 10258.589656413735, 10495.73760655982, 10904.454777261106, 10965.61620343284, 1952.687569988795, 1997.2510308634262, 2001.269536619138, 2025.230412443277, 2054.753429588459, 77916.61041694813, 83583.5820820892, 83584.16415835885, 88560.0, 90234.5488272561, 9946.403215807075, 10325.32885362449, 10568.577256909792, 10627.787444251071, 10647.254692171473, 14281.571552853224, 14345.354459049368, 14349.0, 14349.928250358891, 14350.282485875665, 7323.670434830446, 7800.414968877357, 8284.958575207085, 8366.539840308842, 8448.57757112142, 7718.421118416029, 7797.259260370369, 7856.292933635886, 7898.091719452222, 8119.9188008120345, 5015.598752099896, 5045.722485263345, 5053.242013697901, 5069.389264528771, 5091.215546301849, 2000.3898810862827, 2027.8479114066208, 2135.508832968395, 2140.657494800824, 2143.1427429028204, 976.8446132082524, 995.1740055753708, 1064.8615679961556, 1088.7713580148259, 1089.4607169451172, 18709.345172919155, 19296.4211073668, 19627.60744785125, 19736.40790776279, 19980.10089545974, 998.9700308990742, 1021.0, 1052.8526006359116, 1053.0, 1078.9946050269698, 10049.648262310784, 10737.624183153552, 10765.515551800188, 10806.783864322826, 10857.022867941923, 505265.0, 516872.0, 520288.0, 521252.0, 538633.0, 94646.5267673671, 95160.0, 97649.0, 97822.0, 99750.0, 460.89629833287245, 478.7797613098014, 479.5875547029552, 483.55512928106225, 487.4272729542844, 10795.136389089012, 11042.834357484484, 11428.314301141958, 11492.080633549464, 11829.17195796286, 41287.380689289086, 41370.1725965485, 41980.160396792504, 42909.356359654004, 43683.0, 45199.0, 61396.38603613907, 61442.385576143664, 61625.69187154035, 61783.0, 8904.332175087024, 9157.954210228907, 9377.671781487616, 9412.435253884789, 9457.810843783223, 12370.381480926098, 13519.594412167651, 13846.65383365424, 13939.721205575628, 14057.718845623234, 11404.372759498307, 11542.769144617228, 12179.939100304442, 12290.631281061582, 12446.733131742578, 11921.94039029834, 12028.15802893789, 12176.939115304722, 12191.878081219074, 12567.87432125667, 12366.0, 12400.689982750142, 12469.376531173586, 12646.747065058464, 12965.888186834904, 9174.954125229331, 9242.306826987868, 9261.76845578839, 9321.940738519745, 9413.670521531714, 10773.89226107729, 11916.582919597773, 11991.880081199426, 12110.697232569257, 12407.379631018593], "value": 9094.7375392358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.7232911911294, 1545.2428310128034, 1662.1107707376557, 1837.173272027588, 1852.1942954814665, 8179.345652347813, 8760.912390876088, 8761.824763504726, 8762.34282428818, 8766.956165219173, 7526.962365188167, 7725.150233474314, 8193.672253109884, 8446.113158118402, 8602.698905538307, 2282.224043825099, 2298.4139044543617, 2302.4474126209757, 2308.4113551044543, 2314.0396735354807, 2370.2178281167257, 2371.5494056129364, 2404.9759502405013, 2407.5425669122897, 2411.6623672685837, 2107.9683804742863, 2161.7189765330563, 2164.6969424280614, 2184.071769497965, 2313.9652905206435, 2392.1627430399394, 2425.8787060647073, 2427.599446091393, 2430.1251549442186, 2435.0990133650575, 2337.2871274261342, 2386.2125498585424, 2407.6388541718707, 2411.493586346869, 2413.481101563172, 8587.570621468902, 8634.481931084156, 8659.567021648892, 8665.220130188314, 8665.39342246043, 5088.949110508885, 5283.815066472694, 5294.152935530296, 5301.098813201726, 5301.602379821529, 3225.0808519572042, 3294.637589865113, 3346.247094403764, 3357.244619960514, 3361.512580675813, 1119.2221406122749, 1160.373398364882, 1168.9532418703327, 1179.64020973603, 1195.6413076077229, 11528.769424611544, 11544.249623774513, 11638.48699669046, 11646.068314534818, 11660.533578656843, 4431.7562534060935, 4486.439195100613, 4605.585497305192, 4694.826293426643, 4703.553162449562, 9866.80266394668, 9896.257780666334, 9904.950475247577, 9925.156361709336, 9925.50372481373, 5257.0274499217, 5262.473752624707, 5273.736313184326, 5280.260763493114, 5283.286756287929, 4744.122337367598, 4808.422989241313, 4836.516348365135, 4844.467108618049, 4846.418429788448, 3980.920381592352, 4024.0744628735556, 4098.426220329156, 4098.856540021085, 4144.109016561454, 1270.339423499775, 1283.076185146693, 1286.163993404276, 1298.7402519496138, 1312.5471712259255, 895.8969718482458, 1004.8593196952432, 1016.588281745894, 1018.8828284747349, 1036.9792604147874, 5789.132125571513, 5791.638964843259, 5819.388964158787, 5963.73163207648, 5967.671778052162, 499.16888380845484, 507.45955557331325, 513.4788189987166, 515.0445922813128, 518.6473198225243, 3875.399313106473, 3940.8817735467983, 3963.326234540135, 3973.662238709684, 4043.757374557536, 9521.619135234512, 9925.255605829447, 9926.602935882629, 9978.0, 9994.650187243411, 9555.331126821195, 9576.904230957602, 9708.805823883482, 9725.124738773544, 9757.073078057572, 7513.2862378074, 7532.133804612539, 7591.962040189764, 7592.582407967504, 7597.620118994028, 5472.890542189054, 5610.635308704921, 5706.457886500776, 5721.9700453918695, 5735.225744524436, 9199.758032665504, 9239.168474837297, 9355.158035776813, 9360.0, 9373.0, 9182.17360437564, 9440.575174117044, 9494.240460762985, 9500.42997420157, 9507.286953478655, 7230.204677485578, 7318.743843965436, 7322.926770732224, 7348.412127029718, 7359.926400735924, 7855.921440785519, 7867.449278550446, 7945.0863150738405, 7947.125816160102, 7950.045994480468, 7287.089113860768, 7305.634718264172, 7321.487495875451, 7504.474686771873, 7512.962435187789, 7102.609356485443, 7106.111736032996, 7249.492535522463, 7262.96368518154, 7268.200497945328, 6718.563293385914, 6838.281980391987, 6847.828804279933, 6863.862722745617, 6868.450523957971, 5000.624953128603, 5072.82245121419, 5119.654962411246, 5205.219217117463, 5218.0, 4753.786079626356, 4791.137595232753, 4959.578435832995, 4977.0792403405485, 4982.701037937591], "value": 4453.286338429128, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.638155496407, 312.1182658988357, 317.57920754999617, 324.47921086655913, 327.69360647794315, 5303.496167864057, 5397.919031214535, 5522.779088836452, 5567.49892509674, 5577.274954255946, 5226.163813789794, 5429.755660995254, 5438.265834112403, 5457.699826509541, 5463.180522921563, 4279.465066866642, 4281.143771245747, 4281.935770963438, 4288.9571104289025, 4296.205202037625, 4091.4043523026044, 4137.648299894513, 4219.11398606293, 4266.274733295348, 4280.5291417944, 4211.7262377945335, 4226.852060177895, 4230.17511585241, 4235.915281694364, 4273.67947403946, 4258.574142585748, 4268.210381079494, 4271.337942618899, 4278.315469524892, 4295.162443323553, 3650.1604630935035, 3662.7802331860175, 3681.650243226889, 3701.907452313687, 3783.5838057813617, 5299.788008479656, 5307.495787900143, 5321.255024296563, 5327.866803329948, 5339.41266460689, 4826.396700412449, 4827.155247831615, 4838.6854854434705, 4844.394450693663, 4847.321375007502, 5448.727563621803, 5609.747561359749, 5620.353659329188, 5642.4357564243655, 5748.942510574882, 4073.735207211522, 4073.8370465181356, 4085.407615895708, 4090.652294554937, 4098.585987834193, 5912.083627037816, 5919.822405327804, 5935.821925342247, 5939.465448109648, 5948.464638182541, 3877.2051729395407, 4092.810346464627, 4119.773412462283, 4131.5455299916985, 4179.728317659343, 4157.272477316457, 4160.355144952538, 4164.541900390955, 4172.937405938914, 4179.474491810528, 4542.364069030338, 4587.770611469413, 4650.930236046462, 4717.976410117927, 4740.478547359788, 5706.914396284059, 5711.8857622848145, 5720.942790572124, 5722.084466485342, 5731.713414329267, 1416.4617445370693, 1439.3235179465635, 1444.8988570800154, 1448.478547722819, 1454.7599646058493, 925.2835989239863, 972.4651441707174, 979.9853002204973, 984.6652138273022, 986.1322036607883, 5512.586556008316, 5544.944550554443, 5557.41647127054, 5558.221848941152, 5582.525485333711, 5832.766689332464, 5834.183214349994, 5838.328592211865, 5848.824535263949, 5849.473547380671, 5338.412774594792, 5479.479449452296, 5513.972430137824, 5519.3652729936575, 5522.502974732213, 3078.9384212316077, 3079.707427794356, 3084.4602194615854, 3096.0866544369464, 3107.7203051725, 5857.4435428634215, 5987.550933679991, 6021.337652858182, 6042.667653279024, 6047.818565443044, 5846.503047240947, 5900.262467191601, 5919.911201331984, 5930.318013428425, 5947.613405128739, 5985.713071689607, 5986.2517185351835, 6005.879882402415, 6017.669028203491, 6024.698765061729, 5536.972315138399, 5764.221830052889, 5788.565857560616, 5814.197598744403, 5825.94174058271, 5721.1990321354215, 5748.798792042258, 5755.942440575541, 5781.710914454345, 5785.56608254391, 5847.970760146172, 6021.488173505301, 6026.337102918587, 6071.453569178795, 6076.756929722762, 4130.504339479282, 4134.689898257582, 4140.109876376593, 4147.129102888427, 4156.688248381444, 5702.8289151325525, 5729.713514324184, 5800.941990580209, 5836.737346819488, 5864.179014937998, 5791.681457519877, 5796.4929118430355, 5798.304203495608, 5800.216970708901, 5817.476427121579, 5579.74891129892, 5805.3614102447855, 5819.738111784895, 5824.0, 5844.211031510692, 5676.17695434168, 5688.800891968761, 5705.543556515386, 5707.514861236841, 5729.4843464088435, 5778.451047150514, 5779.566532510163, 5797.246357973437, 5816.621919575383, 5825.475707186373, 5474.890502190014, 5668.688222147656, 5677.858053548694, 5723.914141287967, 5748.051571490657], "value": 4336.391359063934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.2396209544075, 820.3027426687316, 849.5115308697499, 855.2687452228345, 918.9248579162379, 15152.242387880604, 15872.84127158728, 16038.117903515302, 16071.598210044755, 16120.919395403022, 15985.200739962984, 16007.03957762535, 16260.105694186816, 16364.83635163651, 16393.590160246, 852.752701716503, 872.2890843962182, 873.4890089297768, 873.921347078766, 874.9475031498099, 726.2809818279891, 739.693027393631, 860.6213266162869, 868.1448772958631, 874.7200895713374, 915.4141349536269, 921.4885738415177, 921.6728061538158, 922.2253307221932, 922.5479515037616, 923.1138107416855, 923.1368670293298, 923.3075193604797, 923.6305477808863, 924.3852837862813, 549.2941570082425, 561.3292115921475, 562.15115176084, 566.108379302596, 658.0885473619072, 14112.294385280695, 14594.635134121625, 14609.0, 14628.853711462856, 14661.70676586473, 15343.232838358037, 15571.37714491419, 15848.128352940583, 16103.83896161035, 16160.838391616051, 13649.931750341284, 13650.385732642055, 13723.931380343134, 13929.721405571932, 13930.58208253744, 4611.884702882421, 4615.100055489181, 4665.300204969248, 4716.811327546894, 4746.95253047472, 84169.0, 84336.0, 84598.15401845964, 85408.0, 85518.57240713756, 12015.640955393459, 12054.638360849189, 12277.324747138815, 12588.4335204916, 12824.358782060859, 23991.52016959651, 24021.639675404884, 24046.87976560141, 24048.759512404653, 24065.157719479736, 9320.580573874191, 9395.154436100647, 9465.337426380087, 9477.0, 9742.366746161477, 5246.921296180561, 5397.271368365299, 5444.156155795858, 5534.0315444797, 5592.888142237132, 3365.932681346408, 3449.189440481485, 3450.0339904826687, 3470.5661792275964, 3486.511888335635, 546.7621584610628, 554.3154204557369, 568.0258356917906, 569.5927411900511, 573.7647564498535, 648.2447948140458, 665.8734840380313, 711.9359257666835, 718.6155406857306, 729.5695539631643, 9141.131592498701, 9250.629974800933, 9329.906700932905, 9612.711618651452, 9947.0, 529.9417064122944, 563.9605227634027, 577.1112486770411, 596.3380647481292, 668.2181847238743, 4474.060447306102, 4582.060677561092, 4647.117047760915, 4662.557057079572, 4677.298405239241, 76300.61849690716, 77095.0, 77680.0, 130609.34695326653, 132082.33958830335, 100173.99826001792, 113995.43002285101, 115707.96570771509, 117367.0, 122076.0, 100423.49788251011, 101077.0, 101089.0, 105712.0, 106131.4693426528, 8480.533570653673, 8541.786455338417, 8565.657373705231, 8738.781530461534, 8776.452788118931, 58173.4182658168, 58452.0, 59314.40685593089, 59778.804423912145, 60105.69947150236, 83877.0, 87352.0, 89412.5529372349, 90409.54795225982, 90413.0, 12381.504739810307, 12551.497940082294, 12560.685982850136, 12924.806127907901, 13049.151805132637, 14467.85532144665, 14591.343389547283, 14609.0, 14920.925395372953, 15102.924485377504, 14976.47582334613, 15016.549503514912, 15801.525954221392, 16430.424935127212, 16533.83466165323, 14940.0, 14998.850011500183, 15226.162561059247, 15781.0, 16158.596035098746, 13921.651958701113, 14475.0, 14652.560423187322, 14945.626359341104, 15380.230988450756, 10266.435346056038, 10655.57377704883, 10851.782964340511, 11576.24754390962, 11579.13156513248, 11010.0, 11361.147913906325, 11535.480903359201, 11619.360935148648, 11923.523059077539], "value": 7917.28376079577, "unit": "score"}], "name": "dromaeo_css", "value": 7409.711506583317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 111.0, 111.0, 112.0, 111.0, 110.0, 112.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 120.0, 118.0, 155.0, 117.0, 124.0, 117.0, 117.0, 146.0, 122.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 163.0, 159.0, 160.0, 159.0, 160.0, 159.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 74.0, 75.0, 75.0, 74.0, 95.0, 75.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 120.0, 81.0, 81.0, 81.0, 99.0, 81.0, 81.0, 109.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 50.0, 49.0, 49.0, 49.0, 50.0, 49.0, 49.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 80.0, 75.0, 75.0, 74.0, 79.0, 77.0, 75.0, 73.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 129.0, 119.0, 120.0, 120.0, 129.0, 129.0, 122.0, 164.0, 140.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 158.0, 151.0, 151.0, 154.0, 154.0, 152.0, 152.0, 152.0, 152.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 62.0, 63.0, 62.0, 64.0, 62.0, 61.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1455.8999999999999, "alertThreshold": 2.0}]} 09:44:27 INFO - Return code: 0 09:44:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:27 INFO - # TBPL SUCCESS # 09:44:27 INFO - Running post-action listener: _resource_record_post_action 09:44:27 INFO - [mozharness: 2016-10-16 16:44:27.918750Z] Finished run-tests step (success) 09:44:27 INFO - Running post-run listener: _resource_record_post_run 09:44:28 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 369889280; Read time: 472; Write time: 222696 09:44:28 INFO - TinderboxPrint: CPU usage
12.9% 09:44:28 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 472 09:44:28 INFO - TinderboxPrint: I/O write bytes / time
369,889,280 / 222,696 09:44:28 INFO - TinderboxPrint: CPU idle
8,262.6 (87.1%) 09:44:28 INFO - TinderboxPrint: CPU user
1,183.3 (12.5%) 09:44:28 INFO - TinderboxPrint: Swap in / out
0 / 0 09:44:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118771712; Read time: 0; Write time: 69152 09:44:28 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 262144; Write bytes: 250073088; Read time: 24; Write time: 153500 09:44:28 INFO - Running post-run listener: _upload_blobber_files 09:44:28 INFO - Blob upload gear active. 09:44:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:44:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:44:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:44:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:32 INFO - (blobuploader) - INFO - Done attempting. 09:44:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:44:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:44:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:32 INFO - (blobuploader) - INFO - Done attempting. 09:44:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:44:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:44:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:33 INFO - (blobuploader) - INFO - Done attempting. 09:44:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:33 INFO - Return code: 0 09:44:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e28de58713ee184a99ccc5cc5e57de67775226ebac77ed7c5638099228b3f52326ec1240998c98dc8db68001d9826a4d9ab25375457e07987c15b748cc5cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb7d5ef7b1aecbc98dc64bb3275e7e5bcce0c4ae9db5c925a41753bda7a2c5f07497e25ddf70be00e566fc85325ea89142a8ec2027dc57e5a38600d987ad8114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd0ecfaaa431061975e94069bac76c9509db6436ae2d5e4790f1109fae6668214f32ab8f7ef1f5f47e67204156f1ed0ef5e47235ff9f9f82ee1c2437087bdac"} 09:44:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:33 INFO - Contents: 09:44:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e28de58713ee184a99ccc5cc5e57de67775226ebac77ed7c5638099228b3f52326ec1240998c98dc8db68001d9826a4d9ab25375457e07987c15b748cc5cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb7d5ef7b1aecbc98dc64bb3275e7e5bcce0c4ae9db5c925a41753bda7a2c5f07497e25ddf70be00e566fc85325ea89142a8ec2027dc57e5a38600d987ad8114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd0ecfaaa431061975e94069bac76c9509db6436ae2d5e4790f1109fae6668214f32ab8f7ef1f5f47e67204156f1ed0ef5e47235ff9f9f82ee1c2437087bdac"} 09:44:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:33 INFO - Copying logs to upload dir... 09:44:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.523625 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-16 09:44:33.463047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 09:44:33.467587) ========= 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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e28de58713ee184a99ccc5cc5e57de67775226ebac77ed7c5638099228b3f52326ec1240998c98dc8db68001d9826a4d9ab25375457e07987c15b748cc5cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb7d5ef7b1aecbc98dc64bb3275e7e5bcce0c4ae9db5c925a41753bda7a2c5f07497e25ddf70be00e566fc85325ea89142a8ec2027dc57e5a38600d987ad8114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd0ecfaaa431061975e94069bac76c9509db6436ae2d5e4790f1109fae6668214f32ab8f7ef1f5f47e67204156f1ed0ef5e47235ff9f9f82ee1c2437087bdac"} build_url:https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010080 build_url: 'https://queue.taskcluster.net/v1/task/Xxljh6kvT_ObvxHSDedGBw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e28de58713ee184a99ccc5cc5e57de67775226ebac77ed7c5638099228b3f52326ec1240998c98dc8db68001d9826a4d9ab25375457e07987c15b748cc5cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb7d5ef7b1aecbc98dc64bb3275e7e5bcce0c4ae9db5c925a41753bda7a2c5f07497e25ddf70be00e566fc85325ea89142a8ec2027dc57e5a38600d987ad8114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd0ecfaaa431061975e94069bac76c9509db6436ae2d5e4790f1109fae6668214f32ab8f7ef1f5f47e67204156f1ed0ef5e47235ff9f9f82ee1c2437087bdac"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 09:44:33.489696) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:44:33.490001) ========= 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=20e666a3d4cfbb71505a330e000001b4-1476631077.525313-1984396727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004765 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 09:44:33.542842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 09:44:33.546185) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 09:44:33.546495) ========= ========= Total master_lag: 0.31 =========