builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1477598086.57 results: success (0) buildid: 20161027111134 builduid: db173525d7ab4ca297026d8e1f4beee3 revision: 52d62548a29d97d03e2e11ecd16db7602eb93ee7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.571635) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.572598) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.573264) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.590845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.591478) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-27 12:54:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-10-27 12:54:47 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096496 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.706540) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.707140) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017094 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.761046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:46.761874) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 52d62548a29d97d03e2e11ecd16db7602eb93ee7 --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 52d62548a29d97d03e2e11ecd16db7602eb93ee7 --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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-27 12:54:47,835 truncating revision to first 12 chars 2016-10-27 12:54:47,835 Setting DEBUG logging. 2016-10-27 12:54:47,835 attempt 1/10 2016-10-27 12:54:47,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/52d62548a29d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 12:54:48,183 unpacking tar archive at: mozilla-aurora-52d62548a29d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.691511 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:47.467545) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:47.467946) ========= script_repo_revision: 52d62548a29d97d03e2e11ecd16db7602eb93ee7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:47.468468) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:47.468784) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 12:54:47.479958) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-27 12:54:47.480273) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:54:48 INFO - MultiFileLogger online at 20161027 12:54:48 in /builds/slave/test 12:54:48 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 12:54:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:54:48 INFO - {'append_to_log': False, 12:54:48 INFO - 'base_work_dir': '/builds/slave/test', 12:54:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:54:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:54:48 INFO - 'branch': 'Mozilla-Aurora', 12:54:48 INFO - 'buildbot_json_path': 'buildprops.json', 12:54:48 INFO - 'config_files': ('talos/linux_config.py',), 12:54:48 INFO - 'default_actions': ('clobber', 12:54:48 INFO - 'read-buildbot-config', 12:54:48 INFO - 'download-and-extract', 12:54:48 INFO - 'populate-webroot', 12:54:48 INFO - 'create-virtualenv', 12:54:48 INFO - 'install', 12:54:48 INFO - 'run-tests'), 12:54:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:54:48 INFO - 'download_minidump_stackwalk': True, 12:54:48 INFO - 'download_symbols': 'ondemand', 12:54:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:54:48 INFO - 'tooltool.py': '/tools/tooltool.py', 12:54:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:54:48 INFO - '/tools/misc-python/virtualenv.py')}, 12:54:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:54:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:54:48 INFO - 'installer_path': 'installer.exe', 12:54:48 INFO - 'log_level': 'info', 12:54:48 INFO - 'log_name': 'talos', 12:54:48 INFO - 'log_to_console': True, 12:54:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:54:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:54:48 INFO - 'opt_config_files': (), 12:54:48 INFO - 'pip_index': False, 12:54:48 INFO - 'sps_profile': False, 12:54:48 INFO - 'sps_profile_interval': 0, 12:54:48 INFO - 'suite': 'dromaeojs', 12:54:48 INFO - 'system_bits': '32', 12:54:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:54:48 INFO - 'title': 'talos-linux64-ix-062', 12:54:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:54:48 INFO - 'use_talos_json': True, 12:54:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:54:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:54:48 INFO - 'work_dir': 'build'} 12:54:48 INFO - [mozharness: 2016-10-27 19:54:48.643923Z] Running clobber step. 12:54:48 INFO - Running pre-action listener: _resource_record_pre_action 12:54:48 INFO - Running main action method: clobber 12:54:48 INFO - rmtree: /builds/slave/test/build 12:54:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:54:49 INFO - Running post-action listener: _resource_record_post_action 12:54:49 INFO - [mozharness: 2016-10-27 19:54:49.119878Z] Finished clobber step (success) 12:54:49 INFO - [mozharness: 2016-10-27 19:54:49.119980Z] Running read-buildbot-config step. 12:54:49 INFO - Running pre-action listener: _resource_record_pre_action 12:54:49 INFO - Running main action method: read_buildbot_config 12:54:49 INFO - Using buildbot properties: 12:54:49 INFO - { 12:54:49 INFO - "project": "", 12:54:49 INFO - "product": "firefox", 12:54:49 INFO - "script_repo_revision": "production", 12:54:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:54:49 INFO - "repository": "", 12:54:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:54:49 INFO - "buildid": "20161027111134", 12:54:49 INFO - "pgo_build": "True", 12:54:49 INFO - "basedir": "/builds/slave/test", 12:54:49 INFO - "buildnumber": 597, 12:54:49 INFO - "slavename": "talos-linux64-ix-062", 12:54:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:54:49 INFO - "platform": "ubuntu64_hw", 12:54:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:54:49 INFO - "branch": "mozilla-aurora", 12:54:49 INFO - "repo_path": "releases/mozilla-aurora", 12:54:49 INFO - "revision": "52d62548a29d97d03e2e11ecd16db7602eb93ee7", 12:54:49 INFO - "stage_platform": "linux64-pgo", 12:54:49 INFO - "builduid": "db173525d7ab4ca297026d8e1f4beee3", 12:54:49 INFO - "slavebuilddir": "test" 12:54:49 INFO - } 12:54:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 12:54:49 INFO - Running post-action listener: _resource_record_post_action 12:54:49 INFO - [mozharness: 2016-10-27 19:54:49.122466Z] Finished read-buildbot-config step (success) 12:54:49 INFO - [mozharness: 2016-10-27 19:54:49.122556Z] Running download-and-extract step. 12:54:49 INFO - Running pre-action listener: _resource_record_pre_action 12:54:49 INFO - Running main action method: download_and_extract 12:54:49 INFO - mkdir: /builds/slave/test/build/tests 12:54:49 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')]} 12:54:49 INFO - trying https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:54:49 INFO - Downloading https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/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 12:54:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/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 12:54:49 INFO - Downloaded 1361 bytes. 12:54:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:54:49 INFO - Using the following test package requirements: 12:54:49 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:54:49 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:54:49 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:54:49 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'jsshell-linux-x86_64.zip'], 12:54:49 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:54:49 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:54:49 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:54:49 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:54:49 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:54:49 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:54:49 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:54:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:54:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:54:49 INFO - Fetch https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:54:50 INFO - Expected file size: 21894093 12:54:54 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 12:54:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:54:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:54:54 INFO - Fetch https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:54:55 INFO - Expected file size: 21894093 12:54:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 12:54:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:54:59 INFO - Fetch https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 12:55:00 INFO - Expected file size: 14414081 12:55:00 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')]} 12:55:00 INFO - trying https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:55:00 INFO - Downloading https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/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 12:55:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/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 12:55:02 INFO - Downloaded 63955400 bytes. 12:55:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:55:02 INFO - mkdir: /builds/slave/test/properties 12:55:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:55:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:55:02 INFO - Contents: 12:55:02 INFO - build_url:https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:55:02 INFO - Running post-action listener: _resource_record_post_action 12:55:02 INFO - Running post-action listener: set_extra_try_arguments 12:55:02 INFO - [mozharness: 2016-10-27 19:55:02.912230Z] Finished download-and-extract step (success) 12:55:02 INFO - [mozharness: 2016-10-27 19:55:02.912460Z] Running populate-webroot step. 12:55:02 INFO - Running pre-action listener: _resource_record_pre_action 12:55:02 INFO - Running main action method: populate_webroot 12:55:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:55:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:55:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:55:02 INFO - '--tppagecycles', 12:55:02 INFO - '7'], 12:55:02 INFO - 'tests': ['tsvgm']}}, 12:55:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:55:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:55:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:55:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:55:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['damp', 'tps']}, 12:55:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['damp', 'tps']}, 12:55:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['dromaeo_dom']}, 12:55:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:55:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['basic_compositor_video']}, 12:55:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:55:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'other-e10s': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore', 12:55:02 INFO - 'tabpaint']}, 12:55:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['tsvgx', 12:55:02 INFO - 'tsvgr_opacity', 12:55:02 INFO - 'tart', 12:55:02 INFO - 'tscrollx', 12:55:02 INFO - 'cart']}, 12:55:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:55:02 INFO - 'tsvgr_opacity', 12:55:02 INFO - 'tart', 12:55:02 INFO - 'tscrollx', 12:55:02 INFO - 'cart']}, 12:55:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--disable-e10s'], 12:55:02 INFO - 'tests': ['tp5o']}, 12:55:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['tp5o']}, 12:55:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--disable-e10s', 12:55:02 INFO - '--xperf_path', 12:55:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:02 INFO - 'tests': ['tp5n']}, 12:55:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--xperf_path', 12:55:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:02 INFO - 'tests': ['tp5n']}}, 12:55:02 INFO - 'talos.zip': {'path': '', 12:55:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:55:02 INFO - Running post-action listener: _resource_record_post_action 12:55:02 INFO - [mozharness: 2016-10-27 19:55:02.931348Z] Finished populate-webroot step (success) 12:55:02 INFO - [mozharness: 2016-10-27 19:55:02.931437Z] Running create-virtualenv step. 12:55:02 INFO - Running pre-action listener: _resource_record_pre_action 12:55:02 INFO - Running main action method: create_virtualenv 12:55:02 INFO - Creating virtualenv /builds/slave/test/build/venv 12:55:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:55:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:55:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:55:03 INFO - Using real prefix '/usr' 12:55:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:55:03 INFO - Installing distribute.............................................................................................................................................................................................done. 12:55:05 INFO - Installing pip.................done. 12:55:05 INFO - Return code: 0 12:55:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:05 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:05 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 12:55:05 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 12:55:05 INFO - Using env: {'DISPLAY': ':0', 12:55:05 INFO - 'HOME': '/home/cltbld', 12:55:05 INFO - 'LANG': 'en_US.UTF-8', 12:55:05 INFO - 'LANGUAGE': 'en_US:en', 12:55:05 INFO - 'LOGNAME': 'cltbld', 12:55:05 INFO - 'MAIL': '/var/mail/cltbld', 12:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:05 INFO - 'MOZ_NO_REMOTE': '1', 12:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:05 INFO - 'NO_EM_RESTART': '1', 12:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:05 INFO - 'PWD': '/builds/slave/test', 12:55:05 INFO - 'SHELL': '/bin/bash', 12:55:05 INFO - 'SHLVL': '1', 12:55:05 INFO - 'TERM': 'linux', 12:55:05 INFO - 'TMOUT': '86400', 12:55:05 INFO - 'USER': 'cltbld', 12:55:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:05 INFO - '_': '/tools/buildbot/bin/python'} 12:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:05 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 12:55:05 INFO - Cleaning up... 12:55:05 INFO - Return code: 0 12:55:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:05 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:05 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 12:55:05 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 12:55:05 INFO - Using env: {'DISPLAY': ':0', 12:55:05 INFO - 'HOME': '/home/cltbld', 12:55:05 INFO - 'LANG': 'en_US.UTF-8', 12:55:05 INFO - 'LANGUAGE': 'en_US:en', 12:55:05 INFO - 'LOGNAME': 'cltbld', 12:55:05 INFO - 'MAIL': '/var/mail/cltbld', 12:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:05 INFO - 'MOZ_NO_REMOTE': '1', 12:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:05 INFO - 'NO_EM_RESTART': '1', 12:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:05 INFO - 'PWD': '/builds/slave/test', 12:55:05 INFO - 'SHELL': '/bin/bash', 12:55:05 INFO - 'SHLVL': '1', 12:55:05 INFO - 'TERM': 'linux', 12:55:05 INFO - 'TMOUT': '86400', 12:55:05 INFO - 'USER': 'cltbld', 12:55:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:05 INFO - '_': '/tools/buildbot/bin/python'} 12:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:05 INFO - Downloading/unpacking psutil>=3.1.1 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:55:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:07 INFO - Installing collected packages: psutil 12:55:07 INFO - Running setup.py install for psutil 12:55:08 INFO - building 'psutil._psutil_linux' extension 12:55:08 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 12:55:08 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 12:55:08 INFO - building 'psutil._psutil_posix' extension 12:55:08 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 12:55:08 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 12:55:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:08 INFO - Successfully installed psutil 12:55:08 INFO - Cleaning up... 12:55:08 INFO - Return code: 0 12:55:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:55:08 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')]} 12:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:08 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')]} 12:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:08 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:08 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 12:55:08 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 12:55:08 INFO - Using env: {'DISPLAY': ':0', 12:55:08 INFO - 'HOME': '/home/cltbld', 12:55:08 INFO - 'LANG': 'en_US.UTF-8', 12:55:08 INFO - 'LANGUAGE': 'en_US:en', 12:55:08 INFO - 'LOGNAME': 'cltbld', 12:55:08 INFO - 'MAIL': '/var/mail/cltbld', 12:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:08 INFO - 'MOZ_NO_REMOTE': '1', 12:55:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:08 INFO - 'NO_EM_RESTART': '1', 12:55:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:08 INFO - 'PWD': '/builds/slave/test', 12:55:08 INFO - 'SHELL': '/bin/bash', 12:55:08 INFO - 'SHLVL': '1', 12:55:08 INFO - 'TERM': 'linux', 12:55:08 INFO - 'TMOUT': '86400', 12:55:08 INFO - 'USER': 'cltbld', 12:55:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:08 INFO - '_': '/tools/buildbot/bin/python'} 12:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:55:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:55:10 INFO - Installing collected packages: mozsystemmonitor 12:55:10 INFO - Running setup.py install for mozsystemmonitor 12:55:10 INFO - Successfully installed mozsystemmonitor 12:55:10 INFO - Cleaning up... 12:55:10 INFO - Return code: 0 12:55:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:55:10 INFO - Using env: {'DISPLAY': ':0', 12:55:10 INFO - 'HOME': '/home/cltbld', 12:55:10 INFO - 'LANG': 'en_US.UTF-8', 12:55:10 INFO - 'LANGUAGE': 'en_US:en', 12:55:10 INFO - 'LOGNAME': 'cltbld', 12:55:10 INFO - 'MAIL': '/var/mail/cltbld', 12:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:10 INFO - 'MOZ_NO_REMOTE': '1', 12:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:10 INFO - 'NO_EM_RESTART': '1', 12:55:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:10 INFO - 'PWD': '/builds/slave/test', 12:55:10 INFO - 'SHELL': '/bin/bash', 12:55:10 INFO - 'SHLVL': '1', 12:55:10 INFO - 'TERM': 'linux', 12:55:10 INFO - 'TMOUT': '86400', 12:55:10 INFO - 'USER': 'cltbld', 12:55:10 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:10 INFO - '_': '/tools/buildbot/bin/python'} 12:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:11 INFO - Downloading/unpacking jsonschema==2.5.1 12:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:55:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:13 INFO - Downloading functools32-3.2.3-2.tar.gz 12:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:55:13 INFO - warning: no files found matching '*.txt' 12:55:13 INFO - no previously-included directories found matching 'build' 12:55:13 INFO - no previously-included directories found matching 'dist' 12:55:13 INFO - no previously-included directories found matching '.git*' 12:55:13 INFO - Installing collected packages: jsonschema, functools32 12:55:13 INFO - Running setup.py install for functools32 12:55:13 INFO - warning: no files found matching '*.txt' 12:55:13 INFO - no previously-included directories found matching 'build' 12:55:13 INFO - no previously-included directories found matching 'dist' 12:55:13 INFO - no previously-included directories found matching '.git*' 12:55:13 INFO - Successfully installed jsonschema functools32 12:55:13 INFO - Cleaning up... 12:55:13 INFO - Return code: 0 12:55:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:55:13 INFO - Using env: {'DISPLAY': ':0', 12:55:13 INFO - 'HOME': '/home/cltbld', 12:55:13 INFO - 'LANG': 'en_US.UTF-8', 12:55:13 INFO - 'LANGUAGE': 'en_US:en', 12:55:13 INFO - 'LOGNAME': 'cltbld', 12:55:13 INFO - 'MAIL': '/var/mail/cltbld', 12:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:13 INFO - 'MOZ_NO_REMOTE': '1', 12:55:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:13 INFO - 'NO_EM_RESTART': '1', 12:55:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:13 INFO - 'PWD': '/builds/slave/test', 12:55:13 INFO - 'SHELL': '/bin/bash', 12:55:13 INFO - 'SHLVL': '1', 12:55:13 INFO - 'TERM': 'linux', 12:55:13 INFO - 'TMOUT': '86400', 12:55:13 INFO - 'USER': 'cltbld', 12:55:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:13 INFO - '_': '/tools/buildbot/bin/python'} 12:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:55:13 INFO - Cleaning up... 12:55:13 INFO - Return code: 0 12:55:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:55:13 INFO - Using env: {'DISPLAY': ':0', 12:55:13 INFO - 'HOME': '/home/cltbld', 12:55:13 INFO - 'LANG': 'en_US.UTF-8', 12:55:13 INFO - 'LANGUAGE': 'en_US:en', 12:55:13 INFO - 'LOGNAME': 'cltbld', 12:55:13 INFO - 'MAIL': '/var/mail/cltbld', 12:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:13 INFO - 'MOZ_NO_REMOTE': '1', 12:55:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:13 INFO - 'NO_EM_RESTART': '1', 12:55:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:13 INFO - 'PWD': '/builds/slave/test', 12:55:13 INFO - 'SHELL': '/bin/bash', 12:55:13 INFO - 'SHLVL': '1', 12:55:13 INFO - 'TERM': 'linux', 12:55:13 INFO - 'TMOUT': '86400', 12:55:13 INFO - 'USER': 'cltbld', 12:55:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:13 INFO - '_': '/tools/buildbot/bin/python'} 12:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:13 INFO - Downloading/unpacking blobuploader==1.2.4 12:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:15 INFO - Downloading blobuploader-1.2.4.tar.gz 12:55:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:55:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:55:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:16 INFO - Downloading docopt-0.6.1.tar.gz 12:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:55:16 INFO - Installing collected packages: blobuploader, requests, docopt 12:55:16 INFO - Running setup.py install for blobuploader 12:55:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:55:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:55:16 INFO - Running setup.py install for requests 12:55:17 INFO - Running setup.py install for docopt 12:55:17 INFO - Successfully installed blobuploader requests docopt 12:55:17 INFO - Cleaning up... 12:55:17 INFO - Return code: 0 12:55:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:17 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:17 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 12:55:17 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 12:55:17 INFO - Using env: {'DISPLAY': ':0', 12:55:17 INFO - 'HOME': '/home/cltbld', 12:55:17 INFO - 'LANG': 'en_US.UTF-8', 12:55:17 INFO - 'LANGUAGE': 'en_US:en', 12:55:17 INFO - 'LOGNAME': 'cltbld', 12:55:17 INFO - 'MAIL': '/var/mail/cltbld', 12:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:17 INFO - 'MOZ_NO_REMOTE': '1', 12:55:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:17 INFO - 'NO_EM_RESTART': '1', 12:55:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:17 INFO - 'PWD': '/builds/slave/test', 12:55:17 INFO - 'SHELL': '/bin/bash', 12:55:17 INFO - 'SHLVL': '1', 12:55:17 INFO - 'TERM': 'linux', 12:55:17 INFO - 'TMOUT': '86400', 12:55:17 INFO - 'USER': 'cltbld', 12:55:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:17 INFO - '_': '/tools/buildbot/bin/python'} 12:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:17 INFO - Running setup.py (path:/tmp/pip-sXv3qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:17 INFO - Running setup.py (path:/tmp/pip-Ks7mN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:17 INFO - Running setup.py (path:/tmp/pip-4eeTWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:17 INFO - Running setup.py (path:/tmp/pip-24pQeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:17 INFO - Running setup.py (path:/tmp/pip-ce7MwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:17 INFO - Running setup.py (path:/tmp/pip-tnKdmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:17 INFO - Running setup.py (path:/tmp/pip-usQUpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:17 INFO - Running setup.py (path:/tmp/pip-BHpsfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:17 INFO - Running setup.py (path:/tmp/pip-loHuwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:18 INFO - Running setup.py (path:/tmp/pip-hzm25j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:18 INFO - Running setup.py (path:/tmp/pip-zkmhlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:18 INFO - Running setup.py (path:/tmp/pip-52thNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:18 INFO - Running setup.py (path:/tmp/pip-uJ0W2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:18 INFO - Running setup.py (path:/tmp/pip-GE4Cy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:18 INFO - Running setup.py (path:/tmp/pip-IXzh0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:18 INFO - Running setup.py (path:/tmp/pip-_XDtvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:18 INFO - Running setup.py (path:/tmp/pip-Aut_r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:55:18 INFO - Running setup.py install for manifestparser 12:55:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:55:18 INFO - Running setup.py install for mozcrash 12:55:18 INFO - Running setup.py install for mozdebug 12:55:18 INFO - Running setup.py install for mozdevice 12:55:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozfile 12:55:19 INFO - Running setup.py install for mozhttpd 12:55:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozinfo 12:55:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozInstall 12:55:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozleak 12:55:19 INFO - Running setup.py install for mozlog 12:55:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for moznetwork 12:55:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozprocess 12:55:19 INFO - Running setup.py install for mozprofile 12:55:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:55:19 INFO - Running setup.py install for mozrunner 12:55:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:55:20 INFO - Running setup.py install for mozscreenshot 12:55:20 INFO - Running setup.py install for moztest 12:55:20 INFO - Running setup.py install for mozversion 12:55:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:55:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:55:20 INFO - Cleaning up... 12:55:20 INFO - Return code: 0 12:55:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:20 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')]} 12:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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')]} 12:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:20 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 12:55:20 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 12:55:20 INFO - Using env: {'DISPLAY': ':0', 12:55:20 INFO - 'HOME': '/home/cltbld', 12:55:20 INFO - 'LANG': 'en_US.UTF-8', 12:55:20 INFO - 'LANGUAGE': 'en_US:en', 12:55:20 INFO - 'LOGNAME': 'cltbld', 12:55:20 INFO - 'MAIL': '/var/mail/cltbld', 12:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:20 INFO - 'MOZ_NO_REMOTE': '1', 12:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:20 INFO - 'NO_EM_RESTART': '1', 12:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:20 INFO - 'PWD': '/builds/slave/test', 12:55:20 INFO - 'SHELL': '/bin/bash', 12:55:20 INFO - 'SHLVL': '1', 12:55:20 INFO - 'TERM': 'linux', 12:55:20 INFO - 'TMOUT': '86400', 12:55:20 INFO - 'USER': 'cltbld', 12:55:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:20 INFO - '_': '/tools/buildbot/bin/python'} 12:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:20 INFO - Running setup.py (path:/tmp/pip-AWtcrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:20 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)) 12:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:20 INFO - Running setup.py (path:/tmp/pip-E86CDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:20 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)) 12:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:20 INFO - Running setup.py (path:/tmp/pip-0_BSwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:20 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)) 12:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:20 INFO - Running setup.py (path:/tmp/pip-u0ymnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:20 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)) 12:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:20 INFO - Running setup.py (path:/tmp/pip-yfbSbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:21 INFO - Running setup.py (path:/tmp/pip-zfXjts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:21 INFO - Running setup.py (path:/tmp/pip-hOdZvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:21 INFO - Running setup.py (path:/tmp/pip-tFkKkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:21 INFO - Running setup.py (path:/tmp/pip-qcpRDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:21 INFO - Running setup.py (path:/tmp/pip-H5HyO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:21 INFO - Running setup.py (path:/tmp/pip-RZBMyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:21 INFO - Running setup.py (path:/tmp/pip-3A0Yg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:21 INFO - Running setup.py (path:/tmp/pip-jPdIQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:21 INFO - Running setup.py (path:/tmp/pip-tUpTil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:21 INFO - Running setup.py (path:/tmp/pip-YPfdJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:21 INFO - Running setup.py (path:/tmp/pip-cswQKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:21 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)) 12:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:21 INFO - Running setup.py (path:/tmp/pip-KjSNES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:21 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)) 12:55:21 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)) 12:55:21 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)) 12:55:21 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)) 12:55:21 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)) 12:55:21 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)) 12:55:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:23 INFO - Downloading blessings-1.6.tar.gz 12:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:55:23 INFO - Installing collected packages: blessings 12:55:23 INFO - Running setup.py install for blessings 12:55:24 INFO - Successfully installed blessings 12:55:24 INFO - Cleaning up... 12:55:24 INFO - Return code: 0 12:55:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:24 INFO - Reading from file tmpfile_stdout 12:55:24 INFO - Current package versions: 12:55:24 INFO - argparse == 1.2.1 12:55:24 INFO - blessings == 1.6 12:55:24 INFO - blobuploader == 1.2.4 12:55:24 INFO - docopt == 0.6.1 12:55:24 INFO - functools32 == 3.2.3-2 12:55:24 INFO - jsonschema == 2.5.1 12:55:24 INFO - manifestparser == 1.1 12:55:24 INFO - mozInstall == 1.12 12:55:24 INFO - mozcrash == 0.17 12:55:24 INFO - mozdebug == 0.1 12:55:24 INFO - mozdevice == 0.48 12:55:24 INFO - mozfile == 1.2 12:55:24 INFO - mozhttpd == 0.7 12:55:24 INFO - mozinfo == 0.9 12:55:24 INFO - mozleak == 0.1 12:55:24 INFO - mozlog == 3.4 12:55:24 INFO - moznetwork == 0.27 12:55:24 INFO - mozprocess == 0.23 12:55:24 INFO - mozprofile == 0.28 12:55:24 INFO - mozrunner == 6.12 12:55:24 INFO - mozscreenshot == 0.1 12:55:24 INFO - mozsystemmonitor == 0.3 12:55:24 INFO - moztest == 0.7 12:55:24 INFO - mozversion == 1.4 12:55:24 INFO - psutil == 3.1.1 12:55:24 INFO - requests == 1.2.3 12:55:24 INFO - wsgiref == 0.1.2 12:55:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55: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')]} 12:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:24 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 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55:24 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 12:55:24 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 12:55:24 INFO - Using env: {'DISPLAY': ':0', 12:55:24 INFO - 'HOME': '/home/cltbld', 12:55:24 INFO - 'LANG': 'en_US.UTF-8', 12:55:24 INFO - 'LANGUAGE': 'en_US:en', 12:55:24 INFO - 'LOGNAME': 'cltbld', 12:55:24 INFO - 'MAIL': '/var/mail/cltbld', 12:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:24 INFO - 'MOZ_NO_REMOTE': '1', 12:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:24 INFO - 'NO_EM_RESTART': '1', 12:55:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:24 INFO - 'PWD': '/builds/slave/test', 12:55:24 INFO - 'SHELL': '/bin/bash', 12:55:24 INFO - 'SHLVL': '1', 12:55:24 INFO - 'TERM': 'linux', 12:55:24 INFO - 'TMOUT': '86400', 12:55:24 INFO - 'USER': 'cltbld', 12:55:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:24 INFO - '_': '/tools/buildbot/bin/python'} 12:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 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)) 12:55:24 INFO - Cleaning up... 12:55:24 INFO - Return code: 0 12:55:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:55: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')]} 12:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f09d2595f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2320ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', '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 12:55: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'] in /builds/slave/test/build 12:55: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 12:55:24 INFO - Using env: {'DISPLAY': ':0', 12:55:24 INFO - 'HOME': '/home/cltbld', 12:55:24 INFO - 'LANG': 'en_US.UTF-8', 12:55:24 INFO - 'LANGUAGE': 'en_US:en', 12:55:24 INFO - 'LOGNAME': 'cltbld', 12:55:24 INFO - 'MAIL': '/var/mail/cltbld', 12:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:24 INFO - 'MOZ_NO_REMOTE': '1', 12:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:24 INFO - 'NO_EM_RESTART': '1', 12:55:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:24 INFO - 'PWD': '/builds/slave/test', 12:55:24 INFO - 'SHELL': '/bin/bash', 12:55:24 INFO - 'SHLVL': '1', 12:55:24 INFO - 'TERM': 'linux', 12:55:24 INFO - 'TMOUT': '86400', 12:55:24 INFO - 'USER': 'cltbld', 12:55:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:24 INFO - '_': '/tools/buildbot/bin/python'} 12:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:55:24 INFO - Cleaning up... 12:55:24 INFO - Return code: 0 12:55:24 INFO - Running post-action listener: _resource_record_post_action 12:55:24 INFO - Running post-action listener: _start_resource_monitoring 12:55:24 INFO - Starting resource monitoring. 12:55:24 INFO - [mozharness: 2016-10-27 19:55:24.792445Z] Finished create-virtualenv step (success) 12:55:24 INFO - [mozharness: 2016-10-27 19:55:24.792903Z] Running install step. 12:55:24 INFO - Running pre-action listener: _resource_record_pre_action 12:55:24 INFO - Running main action method: install 12:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:25 INFO - Reading from file tmpfile_stdout 12:55:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:55:25 INFO - Reading from file tmpfile_stdout 12:55:25 INFO - Output received: 12:55:25 INFO - Usage: mozinstall [options] installer 12:55:25 INFO - Options: 12:55:25 INFO - -h, --help show this help message and exit 12:55:25 INFO - -d DEST, --destination=DEST 12:55:25 INFO - Directory to install application into. [default: 12:55:25 INFO - "/builds/slave/test"] 12:55:25 INFO - --app=APP Application being installed. [default: firefox] 12:55:25 INFO - mkdir: /builds/slave/test/build/application 12:55:25 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'] 12:55:25 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 12:55:41 INFO - Reading from file tmpfile_stdout 12:55:41 INFO - Output received: 12:55:41 INFO - /builds/slave/test/build/application/firefox/firefox 12:55:41 INFO - Running post-action listener: _resource_record_post_action 12:55:41 INFO - [mozharness: 2016-10-27 19:55:41.073200Z] Finished install step (success) 12:55:41 INFO - [mozharness: 2016-10-27 19:55:41.073450Z] Running run-tests step. 12:55:41 INFO - Running pre-action listener: _resource_record_pre_action 12:55:41 INFO - Running main action method: run_tests 12:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:55:41 INFO - Python 2.7.3 12:55:41 INFO - Return code: 0 12:55:41 INFO - grabbing minidump binary from tooltool 12:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:41 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 0x231df60>, '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 0x231ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x231f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:55:41 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 12:55:41 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 12:55:41 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 12:55:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:55:41 INFO - Return code: 0 12:55:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:55:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:55:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:55:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:55:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:55:41 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/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 12:55:41 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/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 12:55:41 INFO - Using env: {'DISPLAY': ':0', 12:55:41 INFO - 'HOME': '/home/cltbld', 12:55:41 INFO - 'LANG': 'en_US.UTF-8', 12:55:41 INFO - 'LANGUAGE': 'en_US:en', 12:55:41 INFO - 'LOGNAME': 'cltbld', 12:55:41 INFO - 'MAIL': '/var/mail/cltbld', 12:55:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:55:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:41 INFO - 'MOZ_NO_REMOTE': '1', 12:55:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:41 INFO - 'NO_EM_RESTART': '1', 12:55:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:41 INFO - 'PWD': '/builds/slave/test', 12:55:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:55:41 INFO - 'SHELL': '/bin/bash', 12:55:41 INFO - 'SHLVL': '1', 12:55:41 INFO - 'TERM': 'linux', 12:55:41 INFO - 'TMOUT': '86400', 12:55:41 INFO - 'USER': 'cltbld', 12:55:41 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780', 12:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:41 INFO - '_': '/tools/buildbot/bin/python'} 12:55:41 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/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 12:55:41 INFO - mozversion application_buildid: 20161027111134 12:55:41 INFO - mozversion application_changeset: 52d62548a29d97d03e2e11ecd16db7602eb93ee7 12:55:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:55:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:55:41 INFO - mozversion application_name: Firefox 12:55:41 INFO - mozversion application_remotingname: firefox-dev 12:55:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:55:41 INFO - mozversion application_vendor: Mozilla 12:55:41 INFO - mozversion application_version: 51.0a2 12:55:41 INFO - mozversion platform_buildid: 20161027111134 12:55:41 INFO - mozversion platform_changeset: 52d62548a29d97d03e2e11ecd16db7602eb93ee7 12:55:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:55:41 INFO - mozversion platform_version: 51.0a2 12:55:41 INFO - using testdate: 1477598141 12:55:41 INFO - actual date: 1477598141 12:55:41 INFO - starting webserver on 'localhost:52768' 12:55:41 INFO - SUITE-START | Running 2 tests 12:55:41 INFO - TEST-START | dromaeo_css 12:55:41 INFO - operating with platform_type : linux_ 12:55:41 INFO - Initialising browser for dromaeo_css test... 12:55:41 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqwlt7c/profile http://localhost:52768/getInfo.html) 12:55:45 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 12:55:45 INFO - PROCESS | 1952 | colorDepth:24 12:55:45 INFO - PROCESS | 1952 | Browser inner width/height: 1024/697 12:55:45 INFO - PROCESS | 1952 | __metrics 12:55:45 INFO - PROCESS | 1952 | 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" 12:55:45 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:55:45 INFO - PROCESS | 1952 | console.error: PushService: 12:55:45 INFO - PROCESS | 1952 | stateChangeProcessEnqueue: Error transitioning state 12:55:45 INFO - PROCESS | 1952 | UnknownError 12:55:46 INFO - TEST-INFO | 1952: exit 0 12:55:46 INFO - Browser initialized. 12:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 12:55:46 INFO - TEST-INFO | started process 2043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqwlt7c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:55:58 INFO - PROCESS | 2043 | RSS: Main: 144646144 12:55:58 INFO - PROCESS | 2043 | 12:56:49 INFO - PROCESS | 2043 | 1477598209215 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] 12:58:58 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52768/tests/dromaeo/cssquery-ext.html) 12:58:59 INFO - PROCESS | 2043 | RSS: Main: 233959424 12:58:59 INFO - PROCESS | 2043 | 13:02:01 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-ext.html (next: http://localhost:52768/tests/dromaeo/cssquery-jquery.html) 13:02:02 INFO - PROCESS | 2043 | RSS: Main: 234733568 13:02:02 INFO - PROCESS | 2043 | 13:05:04 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52768/tests/dromaeo/cssquery-mootools.html) 13:05:04 INFO - PROCESS | 2043 | RSS: Main: 211632128 13:05:04 INFO - PROCESS | 2043 | 13:08:06 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52768/tests/dromaeo/cssquery-prototype.html) 13:08:07 INFO - PROCESS | 2043 | RSS: Main: 229498880 13:08:07 INFO - PROCESS | 2043 | 13:11:09 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52768/tests/dromaeo/cssquery-yui.html) 13:11:10 INFO - PROCESS | 2043 | RSS: Main: 177008640 13:11:10 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | Cycle 1(1): loaded http://localhost:52768/tests/dromaeo/cssquery-yui.html (next: http://localhost:52768/tests/dromaeo/cssquery-dojo.html) 13:14:13 INFO - PROCESS | 2043 | RSS: Main: 266268672 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | __start_tp_report 13:14:13 INFO - PROCESS | 2043 | _x_x_mozilla_page_load 13:14:13 INFO - PROCESS | 2043 | _x_x_mozilla_page_load_details 13:14:13 INFO - PROCESS | 2043 | |i|pagename|runs| 13:14:13 INFO - PROCESS | 2043 | |0;dojo.html;3039.4984827503463;3047.1315675032606;3073.231692076981;3079.8922037728685;3083.876644934203;5281.551068159206;5391.56867450604;5404.7838086476595;5409.486098820606;5434.049041417756;5420.7018613976215;5421.078416669166;5449.754761035753;5454.4545545445535;5457.072297709389;4903.313536104948;4947.381577302837;4950.158473059589;4994.850154495371;5036.672616279949;4848.539388758062;5019.799208031674;5079.517445842657;5081.034603425337;5122.129238029525;4777.6655634105455;4910.1652719037675;4937.851864444055;4941.2094064949615;4967.503249675022;4972.154733695263;5003.574696150849;5007.824726134568;5076;5104.157813960692;4976.751162441863;4989.101961646902;5015.22264049073;5029.497050294978;5045.2432135180015;5096.184610462308;5181.145111056671;5332.3601167859715;5397.649152805055;5397.973010134963;5899.351071382144;5902.911456328158;5916.970415147896;5935.258092738408;5941.257342832146;5901.822945311647;5924.2298501195;5927.347991720907;5934.28788545373;5935.910961335583;5278.5513231375;5313.628046036779;5390.380106287788;5418.864528386822;5420.105682562333;5968.880622387528;5975.74509353032;5976.252968378953;5985.610935289279;6005.249343832021;5712.171735098471;5713.628614140068;5718.857028574319;5723.856903577361;5724.656520608777;5639.915401268984;5649.661020338793;5706.42935706426;5763.164341170507;5796.275465566804;5735.082386818089;5788.623739457006;5795.362510123883;5821.214136091573;5827.096799996008;5792.536597072224;5805.709714514257;5810.883782324415;5812.9709351452975;5827.446392592697;4394.516603173648;4436.800343984529;4438.023634800339;4453.643708503312;4453.799579018952;3971.1065010373013;3989.6608788253325;4020.6582440492302;4021.4369988201674;4025.1547175092974;5458.1266997280245;5507.944920550824;5511.53151982086;5516.3380394352935;5518.310211223597;5597.720113994284;5655.4910058094965;5765.423457654201;5823.767049318065;5826.067829147316;5435.021696094662;5489.1217405215775;5512.6968016758665;5548.69482178484;5582.944170558324;5029.949700502948;5035.269885866529;5039.546440820343;5043.571296439771;5097.286379906816;5749.88500229993;6063.818085457443;6115.571909966259;6122.418370254819;6128.540359472968;5533.031719449161;5754.338251101177;5793.884122317614;5804.303483581997;5959.28488581373;6113.141862489121;6116.6096904373135;6122.855605810045;6176.166217560572;6182.319944805978;5781.132830075522;5812.564057695774;5815.302163740378;5832.883342333044;5853;5654.85862853432;5723.971380143073;5775.075987841925;5778.162166485752;5934.881302374015;5720.8283751487525;5725.312962444534;5730.054541000855;5730.255066841284;5730.8251808378345;5700.173474846041;5704.3725190228215;5704.486596206361;5708.914366284592;5716.942830571641;5466.207399926909;5586.190002449541;5599.8880022400135;5663.773449061991;5682.090865461506;5674.971625142013;5752.884942301214;5761.596688231783;5770.047942089508;5781.624194427349;5610.49505544503;5612.7754889803955;5615.943840561542;5618.606697531133;5622.24099746529 13:14:13 INFO - PROCESS | 2043 | |1;ext.html;596.2933923300889;628.4595248086646;636.8694417644382;644.4135836388887;668.9331066893308;35442.2911541769;38972.80513597432;45007.999999999956;45994.770026149905;46202.768986155024;48156.75921620396;48210.75894620522;48373;48534.75732621341;48612.27081593779;9275.768105797359;9445.386049906769;9459.952700236488;9463.432194068379;9464.38481498704;8408.495490270603;8657.95671021644;10689.893101068967;11033;11120.054795342365;11237.213395062348;11315.207935444521;11320.830187547193;11323.377214253213;11333.206675532716;11086.113110951144;11102.944485277521;11160.107191424666;11165.274257173298;11166.553337866475;10901.727456813564;10974.890251097546;10980.890191098066;10990.890091099067;10993.945030274877;44330.77834610839;44524.332135018;44676.3298550522;44689.10621787546;44691.776541117084;3887.7667339959694;4013.478247827793;4149.294619914622;4260.382244574519;4586;34096.488552671726;34139.82930085334;34452.48321275158;34602.307953840784;34632.653673463195;4322.82708691652;4338.826446942118;4366.061296821199;4376.912461750779;4421.772106362341;363242;363683;365169;365219;366539;61412.69293653503;61697.383026170064;61822.38177618256;61832.38167618266;61999.6900015497;40050.1989960205;41097;63922;63966.360336396974;64115.35884641187;19467.513312167026;19546;19556.413307600797;19584.902075489532;19626.509337266398;13601;13786.65533361654;15034.323455444532;15156.090634561962;15161.393544258326;20484.897575512026;20770.376888693365;20987.265445709327;21030.579388412145;21120.577588448145;7355;7406.629668516553;7521.435892307989;7719.575423351657;7799.571023593643;1033.3180101133216;1055.2138656700806;1075.1828610256262;1090.3076546392938;1093.136422226448;33689;34156.65843341585;34353;34575;34724.47913281303;1156.1791128298835;1185.7806305833449;1190.3928996211994;1192.4276347353145;1255.1276862580419;17143.228554715068;18146.364877229233;18378.172982215834;18379.54051148732;18399.448016559523;561567;561687;563038;563412;565074;61333.69333153305;61858.38141618616;61876;61920;62056.06915896265;1294.4886769725906;1325.3903204525834;1383.6264208663806;1414.7312010717724;1416.2918540729636;31538.842305789243;34363.4845477323;35022.82488587541;35043.47434788531;35144.47283290701;52681.736591316796;52809;52842.73578632082;53039.46960530345;53094.46905530895;405383;409454;410569;792309;819973;44436.333454997555;44968.55031449644;45112.54887451215;45467;46528.767356163;48951.26573101335;49320.75339623279;49396.75301623613;49421.752891235315;49892.75053624854;41829;42290.57709422989;42439.3634095495;42994.35508467313;43958.12083758371;41303.7934810324;41603;41711.1657766849;43079.7846010768;43306.78346608247;41843.37234941539;41852.790736046125;42830.78584607182;42962.140757185305;43053.78473107614;27607.447851043267;27814.582781257894;28850.855745721976;28901.988430404836;29202;28477;28516.287092822015;28805;29028.27429314284;29032 13:14:13 INFO - PROCESS | 2043 | |2;jquery.html;373.0952440332194;395.3259692224755;405.292764126599;410.9054917369004;416.3255526047803;14312.069715468488;16346.91826540867;20112.195512179533;20719.896400518013;20896.477588060305;16890.915545422286;17289.48131556055;17327.22027508762;18882.150303236354;20470.488237794063;5196.454372290912;5944.940550594503;5957.880842383161;6175.6294622322575;6177.25872895251;6092.51259899207;6117.4188452097205;6155.384461553854;6156.2920264169525;6193.256809182904;5856.238688970428;6009.188759517475;6056.06131049688;6111.786087486938;6121.173641558399;5975.820725378246;5985.5810093293485;5987.670678112702;6025.668588227624;6029.849253768669;5101.0308041472;5194.792208311663;5279.524842764131;5281.128613778722;5290.629655924087;24511;24584.754152458427;24880.5023899521;24947.126850560326;25094;2582.4705935283223;3161.604799400075;3185.681431856796;3250.886218982348;3410.0281419795238;14735.484258050918;15727.44953926607;15972.121533315663;15985.840141598552;16090.678186436206;1960.7745109312468;2101.77931317211;2103.3374487036535;2140.7003019577273;2170.696102545645;64463.355366446674;64589.35410645927;64938.675306623634;81299.5935020327;89064.55467722684;10366.481675916173;10470;10654.840177397346;10752.086072683753;11142.888571114347;14715.852841471662;14734.189619571027;14743.77884331736;14763.77854332186;14809.555713328617;8513.531755753493;8719;8810;8910.153535414125;8914.777130571787;8877;8909.821803563893;8941.642334306554;9181.173694367539;9190.448573085636;6764.086848275519;6767.2894346093935;6778.762743303961;6835.5898646080395;6973.163220413481;1783.777027871516;1844.8985305808042;1851.1114664960894;1865.6175484025741;1892.7255547945747;997.4665146907323;1012.4532752313816;1033.2302434686208;1148.121686909515;1160.4081918221768;20574;21153.25963591267;21593.24423645165;21645.891770541046;21646.567068658853;1050.5010120193;1077.9191560632985;1102.6691992402248;1120.7702421003837;1157.085902137319;11674.649760507198;11926.821097683542;12046.156769026084;12136.332501712313;12281.324527151093;435829;442953;484903;493142;494127;87409;88547.55726221327;91196.54401728082;92722.53638731898;93536.53231733797;1058.142904247562;1059.1950117910444;1062.166199533378;1067.4182570499097;1091.3397394576325;18458.26166953307;18726.719099213795;18777.530561736065;18972.146253418352;19225.03874806282;41499.7925010373;42357.788211058745;44117;47337.52662473331;47894.76052619715;62472;62777;63106;63990.360096400305;64015;8657.696980605648;8850.33622478304;8923.375363724477;9033.367664263438;9198.310126740387;13241.668958276121;13841.238731869442;13857.237851918242;14057.929710351384;14329.498467553585;11930.10524210705;12036.578719744766;12459.439325230205;12486.313252771184;12563.560275390317;11645.59240426581;11679.299242045505;12155.270683759003;12385.318807465675;12421.130520863453;11823.172377933462;11977.041836653221;12468.563600273947;12499.500019999461;12521.93739031299;8774.314787789017;9035.367524273237;9130.634774608941;9173.266138709021;9552.996935321691;8937.195652391316;10343.379397235889;10455.424951627734;10769.892301077203;11715 13:14:13 INFO - PROCESS | 2043 | |3;mootools.html;1400.6708423520465;1484.3913995261944;1538.7845701601775;1667.81654018058;1797.9910100449497;7321.743738969137;8492.278156356717;8860.158284962927;8865.423747456427;9091.727248182566;8860.20258176764;8979.057198994109;8986.910130898688;8987.460752354864;9002.819943601124;2484.6645702830156;2509.5608268553024;2522.608995605684;2525.444402231506;2533.037445770605;2474.232987773787;2522.5207210630015;2545.440003199293;2550.018242976449;2552.9872350638316;2495.1765917247267;2505.4112283613426;2545.312765553309;2546.477972015732;2547.0703193334393;2456.054419048661;2456.250843492725;2470.5800013997573;2498.2755001049673;2505.7243703192635;2414.505026469587;2419.1774796569252;2431.610942249232;2441.5483135619966;2506.6240063990367;8742.694005709833;8888;8889.644414223423;8945.06076861933;8948.418352807046;5441.945580544183;5521.4478552144465;5562.694051827148;5578.804741834057;5605.2713147291;3659.048647351681;3687.94468082979;3695;3742.3076730804614;3744.756590821588;1298.2405292903663;1313.960581182566;1329.3220457566613;1360.6598350412396;1365.2968721108637;12025.579104731292;12099.153059285854;12149.453274602576;12161.148719589632;12194.207376520586;4286.635635970977;4505.6846020778885;4610.53894610543;4625.074985003012;4627.329037289642;9603.087706667855;9604.75988100303;9608.27937904646;9610.66362677303;9647.951760241154;5298.311219541435;5298.947010529845;5308.097623404031;5311.362636483646;5318.627696061239;4105.793952922861;4304.806283717177;4428.357888106207;4517.932231016537;4967.155583550805;3659.1766852457986;4154;4161.521425036099;4171.561985991427;4178.811953462102;1180.0913296761446;1181.3207405741562;1195.462042081067;1208.7824191645411;1239.9318037507844;928.8095940332215;972.1687956796978;993.1508560181086;1008.4806324742764;1023.4831410137833;6123.203983482119;6293.968530157412;6314.242290925118;6486.610803351719;6622.801315960529;505.4793562630496;511.04434707097965;512.0552580488992;522.6132661830218;542.2462776740334;4407.206702793528;4504.842330518416;4524.524924882906;4572.268437050056;4592.88517787058;10621.73445663849;11138.49876755548;11141.94429027866;11143.665690029313;11144.2199046066;11061.502232399562;11066.225364224427;11086.501107450184;11090.114680504334;11100.111991040698;7755.1469338372735;8019.959900200462;8029.237222463857;8182.713605023796;8216.137305583066;6048.667323297261;6181.196444462191;6233.376662333731;6321.618700915918;6383.96808015957;9661;10156.746081348025;10255.282130250811;10331.690049298233;10367;8954.731358059269;9903.059209375098;10170.288079834341;10427.270091093855;10430;7019.894701579378;7569.713148764613;7847.803804904923;7854.41091918097;8074.434789564909;8664.436811607226;8671.523066231417;8696.91303086961;8720.694775682821;8807;7863.724769633035;7939.404544659289;7942.285194332538;7977.282044616012;8144.959275203586;7736.7678969631;7754.883676744967;7768.951191589289;7846.136924938366;7854.1360450349275;6785.93214067853;6855.143107111611;6908.2400935896;6915;6951.895721564281;5173.14643083902;5204.869878253074;5249.7112658804135;5356.732163391737;5387.353517577916;5314.920276195938;5404.486573775485;5435.565154787529;5526.751296191601;5605 13:14:13 INFO - PROCESS | 2043 | |4;prototype.html;332.79699383376135;345.5197275786657;345.59565308588947;346.28837738447487;350.4550424090538;5347.652402593833;5371.301730775003;5382.677039377635;5413.323334583177;5438.592105592076;5296.814611488589;5304.151335786275;5326.467353264681;5337.839864804052;5353.357597088345;4246.982683225474;4250.553691862357;4278.694998025601;4285.35719642053;4286.957130428687;3734.636857546989;3971.8283106483655;4180;4229.767362795061;4260.70175087744;4200.768957707325;4206.705530612859;4210.158143253307;4217.776844715029;4223.704340696153;4213.157368526292;4231.767252801094;4256.425382573344;4266.743995360288;4266.850660226908;3610.584782749991;3655.597884232732;3710.684591809699;3741.625837416264;3755.9483344663536;5207.802205492758;5208.765605547759;5213.6089793265655;5226.712530810804;5274.26160337553;4851.563359297645;4854.029194161147;4854.417469903598;4857;4869.610431165498;5376.569874410038;5540.63985840919;5555.555555555585;5560.2493663355335;5590.524805391547;4140.399642051885;4145.82344269258;4148.087420767426;4153.1486045360625;4158.91682166355;5533.391326954072;5543.281582709333;5879.029960056586;5888.735006924656;5896.086106653475;4118.146833924746;4163.35468002454;4233.25917964355;4245.596668316505;4253.04306531028;4303.634191093792;4315.697901146889;4334.609885110355;4347.543507931685;4349.543297953733;4544.250198717241;4894.486078961658;4908.877278068006;4917.262410638433;4917.286993385939;5637.210790489335;5642.576806739511;5662.122371032414;5672.177534257509;5685.459881311269;1458.4093442155943;1480.6668499587506;1483.0286162563539;1484.9234305128912;1487.8958472906793;920.1902325953253;940.3887473142512;945.4421891084156;947.616215432737;964.2960638733623;4829.541193586604;4856.611471082305;4884.658073934862;4888.044782087193;4923.556879880828;5053.343065401547;5060.975609756053;5064.392272927273;5093.694378337312;5107.208382700687;4675.462321832964;4832.492588278251;4839.395075615548;4859.829905953275;4881.926771098437;2682.906098286551;2689.9058532951253;2690.4081102157493;2690.5157071727276;2694.986525067362;5143.897122057538;5224.242484839677;5232.462255805931;5263.26314315996;5269.473052694777;4887.437944636341;5102.285680004802;5184.455632158644;5187.714675692873;5188.377394712583;5186.988537235278;5194.428612852583;5210.76551555181;5230;5235.497472174004;5023.337631055547;5048.469910659474;5095.974520127524;5105.310783044241;5127.897442051213;4810.663253572357;4980;4989.625778066587;5048.8990220194655;5070.340855688737;5223.5559977402345;5672.829815105553;5749.252597162342;5825.213596164633;5890.52875769946;4169.60388763067;4188.078622703;4196.601322874322;4206.095689426788;4208.473940757405;5615.7192140392;5837.387074357302;5841.912371314349;5842.941570584409;5854.736536855937;5577.163425486209;5605.831825045255;5608.971955140199;5622.212890195294;5659.698269397979;5594.272744543184;5599.412061733622;5639.830805075854;5655.519280861173;5657.4908258256955;5495.835124946258;5561.972190139023;5623.690697011703;5646.753116957683;5652.604317697722;5670.319561652628;5673.553243922938;5681.147827825859;5687.544996400361;5699.800506982236;5552.416996215339;5561.804212094419;5561.972190139023;5563.860903477445;5601.887962240814 13:14:13 INFO - PROCESS | 2043 | |5;yui.html;814.3241109878801;856.4005196362548;890.9955450222748;897.0040066178962;901.9819603607924;15724.371025158995;16596.25316860741;16611.41860034899;16622.83377166228;16662.50012499624;16037.35850565979;16339.509814705578;16356.591085222875;16357.182140892966;16369.672606547862;709.4466316273315;733.2374330696069;831.6174559702544;858.6694122762719;859.5315553023618;860.6944534690158;861.6811779641541;861.7371701631;861.9138086191393;862.3920136303891;915.4461550761794;916.2669864108719;917.5320586500866;917.6100157433065;917.6512925088457;745.0798264143779;765.5598031132066;876.8246350729816;897.4570384917097;911.1480765484293;669.5413641655482;669.7655820462847;669.9631520266383;670.1857243449184;670.5540815357781;15919;15981.760273595906;16023.278952447052;16067.678646427123;16076.115813630246;15560.688786224213;15862.682746345008;15885.36458541657;16066.5983350416;16277.83722162787;13831.585052448443;14031.228282444461;14061.71876562463;14463.27683615815;14672.339744711513;4658.650601204923;4676.625869930397;4770.785314660849;4837.685550439245;4892.094962431962;86133.21829334197;87836.99999999936;89254.10745892522;91674.5416272921;93534;11667;11833.822492662444;12051.33717645538;12489.812652810215;12822.935885320514;22693.773062269494;22798.886005569864;22836.543269134523;22882.970266338056;22951;8480.618372173267;8584.613692383859;8599.140085991476;8670.653173872974;9238.722838314861;4892.339534162914;5064.594832413398;5224.346956630421;5235.764390602432;5307.3896501902;3545.450455179451;3671.30245253401;3671.9632803672157;3673.9265214696093;3680.742348035612;598;602.1329285828393;608.2336256317072;611.3886113886114;626.0484064222356;590.7788848676184;603.499095750526;605.2374008749008;607.5807692692028;688.786476192382;9307;9415.717528474157;9639.214826187115;9958;10289;625.4621025917708;666.5003741581442;670.4334837062675;672;721.3039416962656;4827.155247831615;4909.975450122726;4945.381827271591;4958.281049247839;4985.476524964826;67321;67961.3203867955;68249.31750682529;68749.31250687529;130118;114439;115986.42006789912;116040;118816.02959926071;119242;58787.118193227136;94473.5276323614;98949.50525247327;99424;99839;8479.618417171365;8846.33652476054;9176.58705358247;9292.72121836346;9406.576704048197;59712;59780.70109649424;61231.08153377792;61279.693601531704;62157;81773.59113204396;89895;90465;92574.53712731393;93010;11832.526698931946;12186.939065304618;12282.447289872156;12790.168639038806;13443.663908401977;14007.08953917992;14044.859551404765;14149.787753183917;14344.641383965485;14502.637434064232;13100.213987160801;13271.270080145685;13611.795823062464;14455.566333010027;14919.179445130621;13421.530246441327;13837.584872453843;14343.641408964859;14366.856331436551;14376.85623143797;13063;13085;13998.650033749238;14054.367553460323;14180.432782688576;11434.149062260562;12052.216605920587;12054.457549410123;12265.878465794784;12788.936055319664;11369.374684392438;11619.36093514831;11813.82279265829;11834.52661893549;11948.880511194777 13:14:13 INFO - PROCESS | 2043 | __end_tp_report 13:14:13 INFO - PROCESS | 2043 | __start_cc_report 13:14:13 INFO - PROCESS | 2043 | _x_x_mozilla_cycle_collect,890 13:14:13 INFO - PROCESS | 2043 | __end_cc_report 13:14:13 INFO - PROCESS | 2043 | __startTimestamp1477599253451__endTimestamp 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | ------- Summary: start ------- 13:14:13 INFO - PROCESS | 2043 | Number of tests: 6 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#0] dojo.html Cycles:165 Average:5409.69 Median:5615.94 stddev:624.49 (11.1%) stddev-sans-first:598.08 13:14:13 INFO - PROCESS | 2043 | Values: 3039.5 3047.1 3073.2 3079.9 3083.9 5281.6 5391.6 5404.8 5409.5 5434.0 5420.7 5421.1 5449.8 5454.5 5457.1 4903.3 4947.4 4950.2 4994.9 5036.7 4848.5 5019.8 5079.5 5081.0 5122.1 4777.7 4910.2 4937.9 4941.2 4967.5 4972.2 5003.6 5007.8 5076.0 5104.2 4976.8 4989.1 5015.2 5029.5 5045.2 5096.2 5181.1 5332.4 5397.6 5398.0 5899.4 5902.9 5917.0 5935.3 5941.3 5901.8 5924.2 5927.3 5934.3 5935.9 5278.6 5313.6 5390.4 5418.9 5420.1 5968.9 5975.7 5976.3 5985.6 6005.2 5712.2 5713.6 5718.9 5723.9 5724.7 5639.9 5649.7 5706.4 5763.2 5796.3 5735.1 5788.6 5795.4 5821.2 5827.1 5792.5 5805.7 5810.9 5813.0 5827.4 4394.5 4436.8 4438.0 4453.6 4453.8 3971.1 3989.7 4020.7 4021.4 4025.2 5458.1 5507.9 5511.5 5516.3 5518.3 5597.7 5655.5 5765.4 5823.8 5826.1 5435.0 5489.1 5512.7 5548.7 5582.9 5029.9 5035.3 5039.5 5043.6 5097.3 5749.9 6063.8 6115.6 6122.4 6128.5 5533.0 5754.3 5793.9 5804.3 5959.3 6113.1 6116.6 6122.9 6176.2 6182.3 5781.1 5812.6 5815.3 5832.9 5853.0 5654.9 5724.0 5775.1 5778.2 5934.9 5720.8 5725.3 5730.1 5730.3 5730.8 5700.2 5704.4 5704.5 5708.9 5716.9 5466.2 5586.2 5599.9 5663.8 5682.1 5675.0 5752.9 5761.6 5770.0 5781.6 5610.5 5612.8 5615.9 5618.6 5622.2 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#1] ext.html Cycles:180 Average:66393.26 Median:225833.00 stddev:138649.08 (61.4%) stddev-sans-first:138950.02 13:14:13 INFO - PROCESS | 2043 | Values: 596.3 628.5 636.9 644.4 668.9 35442.3 38972.8 45008.0 45994.8 46202.8 48156.8 48210.8 48373.0 48534.8 48612.3 9275.8 9445.4 9460.0 9463.4 9464.4 8408.5 8658.0 10689.9 11033.0 11120.1 11237.2 11315.2 11320.8 11323.4 11333.2 11086.1 11102.9 11160.1 11165.3 11166.6 10901.7 10974.9 10980.9 10990.9 10993.9 44330.8 44524.3 44676.3 44689.1 44691.8 3887.8 4013.5 4149.3 4260.4 4586.0 34096.5 34139.8 34452.5 34602.3 34632.7 4322.8 4338.8 4366.1 4376.9 4421.8 363242.0 363683.0 365169.0 365219.0 366539.0 61412.7 61697.4 61822.4 61832.4 61999.7 40050.2 41097.0 63922.0 63966.4 64115.4 19467.5 19546.0 19556.4 19584.9 19626.5 13601.0 13786.7 15034.3 15156.1 15161.4 20484.9 20770.4 20987.3 21030.6 21120.6 7355.0 7406.6 7521.4 7719.6 7799.6 1033.3 1055.2 1075.2 1090.3 1093.1 33689.0 34156.7 34353.0 34575.0 34724.5 1156.2 1185.8 1190.4 1192.4 1255.1 17143.2 18146.4 18378.2 18379.5 18399.4 561567.0 561687.0 563038.0 563412.0 565074.0 61333.7 61858.4 61876.0 61920.0 62056.1 1294.5 1325.4 1383.6 1414.7 1416.3 31538.8 34363.5 35022.8 35043.5 35144.5 52681.7 52809.0 52842.7 53039.5 53094.5 405383.0 409454.0 410569.0 792309.0 819973.0 44436.3 44968.6 45112.5 45467.0 46528.8 48951.3 49320.8 49396.8 49421.8 49892.8 41829.0 42290.6 42439.4 42994.4 43958.1 41303.8 41603.0 41711.2 43079.8 43306.8 41843.4 41852.8 42830.8 42962.1 43053.8 27607.4 27814.6 28850.9 28902.0 29202.0 28477.0 28516.3 28805.0 29028.3 29032.0 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#2] jquery.html Cycles:180 Average:28889.19 Median:24913.81 stddev:77580.54 (311.4%) stddev-sans-first:77768.63 13:14:13 INFO - PROCESS | 2043 | Values: 373.1 395.3 405.3 410.9 416.3 14312.1 16346.9 20112.2 20719.9 20896.5 16890.9 17289.5 17327.2 18882.2 20470.5 5196.5 5944.9 5957.9 6175.6 6177.3 6092.5 6117.4 6155.4 6156.3 6193.3 5856.2 6009.2 6056.1 6111.8 6121.2 5975.8 5985.6 5987.7 6025.7 6029.8 5101.0 5194.8 5279.5 5281.1 5290.6 24511.0 24584.8 24880.5 24947.1 25094.0 2582.5 3161.6 3185.7 3250.9 3410.0 14735.5 15727.4 15972.1 15985.8 16090.7 1960.8 2101.8 2103.3 2140.7 2170.7 64463.4 64589.4 64938.7 81299.6 89064.6 10366.5 10470.0 10654.8 10752.1 11142.9 14715.9 14734.2 14743.8 14763.8 14809.6 8513.5 8719.0 8810.0 8910.2 8914.8 8877.0 8909.8 8941.6 9181.2 9190.4 6764.1 6767.3 6778.8 6835.6 6973.2 1783.8 1844.9 1851.1 1865.6 1892.7 997.5 1012.5 1033.2 1148.1 1160.4 20574.0 21153.3 21593.2 21645.9 21646.6 1050.5 1077.9 1102.7 1120.8 1157.1 11674.6 11926.8 12046.2 12136.3 12281.3 435829.0 442953.0 484903.0 493142.0 494127.0 87409.0 88547.6 91196.5 92722.5 93536.5 1058.1 1059.2 1062.2 1067.4 1091.3 18458.3 18726.7 18777.5 18972.1 19225.0 41499.8 42357.8 44117.0 47337.5 47894.8 62472.0 62777.0 63106.0 63990.4 64015.0 8657.7 8850.3 8923.4 9033.4 9198.3 13241.7 13841.2 13857.2 14057.9 14329.5 11930.1 12036.6 12459.4 12486.3 12563.6 11645.6 11679.3 12155.3 12385.3 12421.1 11823.2 11977.0 12468.6 12499.5 12521.9 8774.3 9035.4 9130.6 9173.3 9553.0 8937.2 10343.4 10455.4 10769.9 11715.0 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#3] mootools.html Cycles:180 Average:5861.58 Median:511.55 stddev:3255.27 (636.4%) stddev-sans-first:3247.14 13:14:13 INFO - PROCESS | 2043 | Values: 1400.7 1484.4 1538.8 1667.8 1798.0 7321.7 8492.3 8860.2 8865.4 9091.7 8860.2 8979.1 8986.9 8987.5 9002.8 2484.7 2509.6 2522.6 2525.4 2533.0 2474.2 2522.5 2545.4 2550.0 2553.0 2495.2 2505.4 2545.3 2546.5 2547.1 2456.1 2456.3 2470.6 2498.3 2505.7 2414.5 2419.2 2431.6 2441.5 2506.6 8742.7 8888.0 8889.6 8945.1 8948.4 5441.9 5521.4 5562.7 5578.8 5605.3 3659.0 3687.9 3695.0 3742.3 3744.8 1298.2 1314.0 1329.3 1360.7 1365.3 12025.6 12099.2 12149.5 12161.1 12194.2 4286.6 4505.7 4610.5 4625.1 4627.3 9603.1 9604.8 9608.3 9610.7 9648.0 5298.3 5298.9 5308.1 5311.4 5318.6 4105.8 4304.8 4428.4 4517.9 4967.2 3659.2 4154.0 4161.5 4171.6 4178.8 1180.1 1181.3 1195.5 1208.8 1239.9 928.8 972.2 993.2 1008.5 1023.5 6123.2 6294.0 6314.2 6486.6 6622.8 505.5 511.0 512.1 522.6 542.2 4407.2 4504.8 4524.5 4572.3 4592.9 10621.7 11138.5 11141.9 11143.7 11144.2 11061.5 11066.2 11086.5 11090.1 11100.1 7755.1 8020.0 8029.2 8182.7 8216.1 6048.7 6181.2 6233.4 6321.6 6384.0 9661.0 10156.7 10255.3 10331.7 10367.0 8954.7 9903.1 10170.3 10427.3 10430.0 7019.9 7569.7 7847.8 7854.4 8074.4 8664.4 8671.5 8696.9 8720.7 8807.0 7863.7 7939.4 7942.3 7977.3 8145.0 7736.8 7754.9 7769.0 7846.1 7854.1 6785.9 6855.1 6908.2 6915.0 6951.9 5173.1 5204.9 5249.7 5356.7 5387.4 5314.9 5404.5 5435.6 5526.8 5605.0 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#4] prototype.html Cycles:180 Average:4592.75 Median:5082.02 stddev:1312.22 (25.8%) stddev-sans-first:1276.35 13:14:13 INFO - PROCESS | 2043 | Values: 332.8 345.5 345.6 346.3 350.5 5347.7 5371.3 5382.7 5413.3 5438.6 5296.8 5304.2 5326.5 5337.8 5353.4 4247.0 4250.6 4278.7 4285.4 4287.0 3734.6 3971.8 4180.0 4229.8 4260.7 4200.8 4206.7 4210.2 4217.8 4223.7 4213.2 4231.8 4256.4 4266.7 4266.9 3610.6 3655.6 3710.7 3741.6 3755.9 5207.8 5208.8 5213.6 5226.7 5274.3 4851.6 4854.0 4854.4 4857.0 4869.6 5376.6 5540.6 5555.6 5560.2 5590.5 4140.4 4145.8 4148.1 4153.1 4158.9 5533.4 5543.3 5879.0 5888.7 5896.1 4118.1 4163.4 4233.3 4245.6 4253.0 4303.6 4315.7 4334.6 4347.5 4349.5 4544.3 4894.5 4908.9 4917.3 4917.3 5637.2 5642.6 5662.1 5672.2 5685.5 1458.4 1480.7 1483.0 1484.9 1487.9 920.2 940.4 945.4 947.6 964.3 4829.5 4856.6 4884.7 4888.0 4923.6 5053.3 5061.0 5064.4 5093.7 5107.2 4675.5 4832.5 4839.4 4859.8 4881.9 2682.9 2689.9 2690.4 2690.5 2695.0 5143.9 5224.2 5232.5 5263.3 5269.5 4887.4 5102.3 5184.5 5187.7 5188.4 5187.0 5194.4 5210.8 5230.0 5235.5 5023.3 5048.5 5096.0 5105.3 5127.9 4810.7 4980.0 4989.6 5048.9 5070.3 5223.6 5672.8 5749.3 5825.2 5890.5 4169.6 4188.1 4196.6 4206.1 4208.5 5615.7 5837.4 5841.9 5842.9 5854.7 5577.2 5605.8 5609.0 5622.2 5659.7 5594.3 5599.4 5639.8 5655.5 5657.5 5495.8 5562.0 5623.7 5646.8 5652.6 5670.3 5673.6 5681.1 5687.5 5699.8 5552.4 5561.8 5562.0 5563.9 5601.9 13:14:13 INFO - PROCESS | 2043 | 13:14:13 INFO - PROCESS | 2043 | [#5] yui.html Cycles:180 Average:21850.84 Median:4951.83 stddev:31379.15 (633.7%) stddev-sans-first:31427.42 13:14:13 INFO - PROCESS | 2043 | Values: 814.3 856.4 891.0 897.0 902.0 15724.4 16596.3 16611.4 16622.8 16662.5 16037.4 16339.5 16356.6 16357.2 16369.7 709.4 733.2 831.6 858.7 859.5 860.7 861.7 861.7 861.9 862.4 915.4 916.3 917.5 917.6 917.7 745.1 765.6 876.8 897.5 911.1 669.5 669.8 670.0 670.2 670.6 15919.0 15981.8 16023.3 16067.7 16076.1 15560.7 15862.7 15885.4 16066.6 16277.8 13831.6 14031.2 14061.7 14463.3 14672.3 4658.7 4676.6 4770.8 4837.7 4892.1 86133.2 87837.0 89254.1 91674.5 93534.0 11667.0 11833.8 12051.3 12489.8 12822.9 22693.8 22798.9 22836.5 22883.0 22951.0 8480.6 8584.6 8599.1 8670.7 9238.7 4892.3 5064.6 5224.3 5235.8 5307.4 3545.5 3671.3 3672.0 3673.9 3680.7 598.0 602.1 608.2 611.4 626.0 590.8 603.5 605.2 607.6 688.8 9307.0 9415.7 9639.2 9958.0 10289.0 625.5 666.5 670.4 672.0 721.3 4827.2 4910.0 4945.4 4958.3 4985.5 67321.0 67961.3 68249.3 68749.3 130118.0 114439.0 115986.4 116040.0 118816.0 119242.0 58787.1 94473.5 98949.5 99424.0 99839.0 8479.6 8846.3 9176.6 9292.7 9406.6 59712.0 59780.7 61231.1 61279.7 62157.0 81773.6 89895.0 90465.0 92574.5 93010.0 11832.5 12186.9 12282.4 12790.2 13443.7 14007.1 14044.9 14149.8 14344.6 14502.6 13100.2 13271.3 13611.8 14455.6 14919.2 13421.5 13837.6 14343.6 14366.9 14376.9 13063.0 13085.0 13998.7 14054.4 14180.4 11434.1 12052.2 12054.5 12265.9 12788.9 11369.4 11619.4 11813.8 11834.5 11948.9 13:14:13 INFO - PROCESS | 2043 | -------- Summary: end -------- 13:14:13 INFO - PROCESS | 2043 | 13:14:14 INFO - TEST-INFO | 2043: exit 0 13:14:14 INFO - TEST-OK | dromaeo_css | took 1112869ms 13:14:14 INFO - TEST-START | kraken 13:14:14 INFO - operating with platform_type : linux_ 13:14:14 INFO - Initialising browser for kraken test... 13:14:14 INFO - TEST-INFO | started process 2436 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHVPkru/profile http://localhost:52768/getInfo.html) 13:14:17 INFO - PROCESS | 2436 | __metrics Screen width/height:1600/1200 13:14:17 INFO - PROCESS | 2436 | colorDepth:24 13:14:17 INFO - PROCESS | 2436 | Browser inner width/height: 1024/697 13:14:17 INFO - PROCESS | 2436 | __metrics 13:14:18 INFO - PROCESS | 2436 | 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" 13:14:18 INFO - PROCESS | 2436 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:14:19 INFO - PROCESS | 2436 | console.error: PushService: 13:14:19 INFO - PROCESS | 2436 | stateChangeProcessEnqueue: Error transitioning state 13:14:19 INFO - PROCESS | 2436 | UnknownError 13:14:19 INFO - TEST-INFO | 2436: exit 0 13:14:19 INFO - Browser initialized. 13:14:19 INFO - Running cycle 1/1 for kraken test... 13:14:19 INFO - TEST-INFO | started process 2548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHVPkru/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:14:31 INFO - PROCESS | 2548 | RSS: Main: 144916480 13:14:31 INFO - PROCESS | 2548 | 13:14:33 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52768/tests/kraken/driver.html?testName=audio-beat-detection) 13:14:34 INFO - PROCESS | 2548 | RSS: Main: 186011648 13:14:34 INFO - PROCESS | 2548 | 13:14:37 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52768/tests/kraken/driver.html?testName=audio-dft) 13:14:37 INFO - PROCESS | 2548 | RSS: Main: 175304704 13:14:37 INFO - PROCESS | 2548 | 13:14:40 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52768/tests/kraken/driver.html?testName=audio-fft) 13:14:41 INFO - PROCESS | 2548 | RSS: Main: 266813440 13:14:41 INFO - PROCESS | 2548 | 13:14:43 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52768/tests/kraken/driver.html?testName=audio-oscillator) 13:14:43 INFO - PROCESS | 2548 | RSS: Main: 172683264 13:14:43 INFO - PROCESS | 2548 | 13:14:46 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:14:46 INFO - PROCESS | 2548 | RSS: Main: 174399488 13:14:46 INFO - PROCESS | 2548 | 13:14:49 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52768/tests/kraken/driver.html?testName=imaging-darkroom) 13:14:50 INFO - PROCESS | 2548 | RSS: Main: 210997248 13:14:50 INFO - PROCESS | 2548 | 13:14:53 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52768/tests/kraken/driver.html?testName=imaging-desaturate) 13:14:54 INFO - PROCESS | 2548 | RSS: Main: 209989632 13:14:54 INFO - PROCESS | 2548 | 13:14:56 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52768/tests/kraken/driver.html?testName=json-parse-financial) 13:14:57 INFO - PROCESS | 2548 | RSS: Main: 209821696 13:14:57 INFO - PROCESS | 2548 | 13:14:59 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:14:59 INFO - PROCESS | 2548 | RSS: Main: 171319296 13:14:59 INFO - PROCESS | 2548 | 13:15:01 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:15:01 INFO - PROCESS | 2548 | RSS: Main: 178880512 13:15:01 INFO - PROCESS | 2548 | 13:15:03 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:15:04 INFO - PROCESS | 2548 | RSS: Main: 188547072 13:15:04 INFO - PROCESS | 2548 | 13:15:07 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:15:07 INFO - PROCESS | 2548 | RSS: Main: 197373952 13:15:07 INFO - PROCESS | 2548 | 13:15:10 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:15:11 INFO - PROCESS | 2548 | RSS: Main: 194478080 13:15:11 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | Cycle 1(1): loaded http://localhost:52768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52768/tests/kraken/driver.html?testName=ai-astar) 13:15:12 INFO - PROCESS | 2548 | RSS: Main: 243122176 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | __start_tp_report 13:15:12 INFO - PROCESS | 2548 | _x_x_mozilla_page_load 13:15:12 INFO - PROCESS | 2548 | _x_x_mozilla_page_load_details 13:15:12 INFO - PROCESS | 2548 | |i|pagename|runs| 13:15:12 INFO - PROCESS | 2548 | |0;ai-astar;111;113;114;113;112;113;113;112;111;112 13:15:12 INFO - PROCESS | 2548 | |1;audio-beat-detection;142;118;120;155;128;121;119;119;149;117 13:15:12 INFO - PROCESS | 2548 | |2;audio-dft;162;162;160;162;162;161;164;162;161;161 13:15:12 INFO - PROCESS | 2548 | |3;audio-fft;75;76;76;112;76;77;78;75;100;77 13:15:12 INFO - PROCESS | 2548 | |4;audio-oscillator;82;82;108;82;82;82;110;83;83;111 13:15:12 INFO - PROCESS | 2548 | |5;imaging-gaussian-blur;119;119;119;119;118;117;118;117;118;118 13:15:12 INFO - PROCESS | 2548 | |6;imaging-darkroom;146;145;147;146;147;145;146;147;148;148 13:15:12 INFO - PROCESS | 2548 | |7;imaging-desaturate;94;95;95;94;95;95;95;95;95;96 13:15:12 INFO - PROCESS | 2548 | |8;json-parse-financial;50;50;49;49;49;48;49;48;50;50 13:15:12 INFO - PROCESS | 2548 | |9;json-stringify-tinderbox;60;60;59;59;59;65;60;60;60;60 13:15:12 INFO - PROCESS | 2548 | |10;stanford-crypto-aes;75;83;74;75;83;73;74;83;73;74 13:15:12 INFO - PROCESS | 2548 | |11;stanford-crypto-ccm;124;124;123;129;130;118;118;127;169;121 13:15:12 INFO - PROCESS | 2548 | |12;stanford-crypto-pbkdf2;159;164;155;157;161;155;158;160;161;156 13:15:12 INFO - PROCESS | 2548 | |13;stanford-crypto-sha256-iterative;63;63;64;61;61;68;65;62;61;61 13:15:12 INFO - PROCESS | 2548 | __end_tp_report 13:15:12 INFO - PROCESS | 2548 | __start_cc_report 13:15:12 INFO - PROCESS | 2548 | _x_x_mozilla_cycle_collect,1581 13:15:12 INFO - PROCESS | 2548 | __end_cc_report 13:15:12 INFO - PROCESS | 2548 | __startTimestamp1477599312923__endTimestamp 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | ------- Summary: start ------- 13:15:12 INFO - PROCESS | 2548 | Number of tests: 14 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:0.88 13:15:12 INFO - PROCESS | 2548 | Values: 111.0 113.0 114.0 113.0 112.0 113.0 113.0 112.0 111.0 112.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:124.50 stddev:14.36 (11.5%) stddev-sans-first:14.41 13:15:12 INFO - PROCESS | 2548 | Values: 142.0 118.0 120.0 155.0 128.0 121.0 119.0 119.0 149.0 117.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 13:15:12 INFO - PROCESS | 2548 | Values: 162.0 162.0 160.0 162.0 162.0 161.0 164.0 162.0 161.0 161.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:12.89 (17.0%) stddev-sans-first:13.41 13:15:12 INFO - PROCESS | 2548 | Values: 75.0 76.0 76.0 112.0 76.0 77.0 78.0 75.0 100.0 77.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:82.00 stddev:13.25 (16.2%) stddev-sans-first:13.69 13:15:12 INFO - PROCESS | 2548 | Values: 82.0 82.0 108.0 82.0 82.0 82.0 110.0 83.0 83.0 111.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.50 stddev:0.79 (0.7%) stddev-sans-first:0.78 13:15:12 INFO - PROCESS | 2548 | Values: 119.0 119.0 119.0 119.0 118.0 117.0 118.0 117.0 118.0 118.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 13:15:12 INFO - PROCESS | 2548 | Values: 146.0 145.0 147.0 146.0 147.0 145.0 146.0 147.0 148.0 148.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 13:15:12 INFO - PROCESS | 2548 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 96.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.50 stddev:0.79 (1.6%) stddev-sans-first:0.78 13:15:12 INFO - PROCESS | 2548 | Values: 50.0 50.0 49.0 49.0 49.0 48.0 49.0 48.0 50.0 50.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:1.75 (2.9%) stddev-sans-first:1.86 13:15:12 INFO - PROCESS | 2548 | Values: 60.0 60.0 59.0 59.0 59.0 65.0 60.0 60.0 60.0 60.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:75.00 stddev:4.40 (5.9%) stddev-sans-first:4.62 13:15:12 INFO - PROCESS | 2548 | Values: 75.0 83.0 74.0 75.0 83.0 73.0 74.0 83.0 73.0 74.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:125.50 stddev:14.88 (11.9%) stddev-sans-first:15.70 13:15:12 INFO - PROCESS | 2548 | Values: 124.0 124.0 123.0 129.0 130.0 118.0 118.0 127.0 169.0 121.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:159.50 stddev:2.95 (1.9%) stddev-sans-first:3.13 13:15:12 INFO - PROCESS | 2548 | Values: 159.0 164.0 155.0 157.0 161.0 155.0 158.0 160.0 161.0 156.0 13:15:12 INFO - PROCESS | 2548 | 13:15:12 INFO - PROCESS | 2548 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:2.28 (3.6%) stddev-sans-first:2.42 13:15:12 INFO - PROCESS | 2548 | Values: 63.0 63.0 64.0 61.0 61.0 68.0 65.0 62.0 61.0 61.0 13:15:12 INFO - PROCESS | 2548 | -------- Summary: end -------- 13:15:12 INFO - PROCESS | 2548 | 13:15:14 INFO - TEST-INFO | 2548: exit 0 13:15:14 INFO - TEST-OK | kraken | took 59871ms 13:15:14 INFO - SUITE-END | took 1172s 13:15:14 INFO - Completed test suite (00:19:33) 13:15:14 INFO - javascript benchmark 13:15:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3039.4984827503463, 3047.1315675032606, 3073.231692076981, 3079.8922037728685, 3083.876644934203, 5281.551068159206, 5391.56867450604, 5404.7838086476595, 5409.486098820606, 5434.049041417756, 5420.7018613976215, 5421.078416669166, 5449.754761035753, 5454.4545545445535, 5457.072297709389, 4903.313536104948, 4947.381577302837, 4950.158473059589, 4994.850154495371, 5036.672616279949, 4848.539388758062, 5019.799208031674, 5079.517445842657, 5081.034603425337, 5122.129238029525, 4777.6655634105455, 4910.1652719037675, 4937.851864444055, 4941.2094064949615, 4967.503249675022, 4972.154733695263, 5003.574696150849, 5007.824726134568, 5076.0, 5104.157813960692, 4976.751162441863, 4989.101961646902, 5015.22264049073, 5029.497050294978, 5045.2432135180015, 5096.184610462308, 5181.145111056671, 5332.3601167859715, 5397.649152805055, 5397.973010134963, 5899.351071382144, 5902.911456328158, 5916.970415147896, 5935.258092738408, 5941.257342832146, 5901.822945311647, 5924.2298501195, 5927.347991720907, 5934.28788545373, 5935.910961335583, 5278.5513231375, 5313.628046036779, 5390.380106287788, 5418.864528386822, 5420.105682562333, 5968.880622387528, 5975.74509353032, 5976.252968378953, 5985.610935289279, 6005.249343832021, 5712.171735098471, 5713.628614140068, 5718.857028574319, 5723.856903577361, 5724.656520608777, 5639.915401268984, 5649.661020338793, 5706.42935706426, 5763.164341170507, 5796.275465566804, 5735.082386818089, 5788.623739457006, 5795.362510123883, 5821.214136091573, 5827.096799996008, 5792.536597072224, 5805.709714514257, 5810.883782324415, 5812.9709351452975, 5827.446392592697, 4394.516603173648, 4436.800343984529, 4438.023634800339, 4453.643708503312, 4453.799579018952, 3971.1065010373013, 3989.6608788253325, 4020.6582440492302, 4021.4369988201674, 4025.1547175092974, 5458.1266997280245, 5507.944920550824, 5511.53151982086, 5516.3380394352935, 5518.310211223597, 5597.720113994284, 5655.4910058094965, 5765.423457654201, 5823.767049318065, 5826.067829147316, 5435.021696094662, 5489.1217405215775, 5512.6968016758665, 5548.69482178484, 5582.944170558324, 5029.949700502948, 5035.269885866529, 5039.546440820343, 5043.571296439771, 5097.286379906816, 5749.88500229993, 6063.818085457443, 6115.571909966259, 6122.418370254819, 6128.540359472968, 5533.031719449161, 5754.338251101177, 5793.884122317614, 5804.303483581997, 5959.28488581373, 6113.141862489121, 6116.6096904373135, 6122.855605810045, 6176.166217560572, 6182.319944805978, 5781.132830075522, 5812.564057695774, 5815.302163740378, 5832.883342333044, 5853.0, 5654.85862853432, 5723.971380143073, 5775.075987841925, 5778.162166485752, 5934.881302374015, 5720.8283751487525, 5725.312962444534, 5730.054541000855, 5730.255066841284, 5730.8251808378345, 5700.173474846041, 5704.3725190228215, 5704.486596206361, 5708.914366284592, 5716.942830571641, 5466.207399926909, 5586.190002449541, 5599.8880022400135, 5663.773449061991, 5682.090865461506, 5674.971625142013, 5752.884942301214, 5761.596688231783, 5770.047942089508, 5781.624194427349, 5610.49505544503, 5612.7754889803955, 5615.943840561542, 5618.606697531133, 5622.24099746529], "value": 5366.466978548936, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.2933923300889, 628.4595248086646, 636.8694417644382, 644.4135836388887, 668.9331066893308, 35442.2911541769, 38972.80513597432, 45007.999999999956, 45994.770026149905, 46202.768986155024, 48156.75921620396, 48210.75894620522, 48373.0, 48534.75732621341, 48612.27081593779, 9275.768105797359, 9445.386049906769, 9459.952700236488, 9463.432194068379, 9464.38481498704, 8408.495490270603, 8657.95671021644, 10689.893101068967, 11033.0, 11120.054795342365, 11237.213395062348, 11315.207935444521, 11320.830187547193, 11323.377214253213, 11333.206675532716, 11086.113110951144, 11102.944485277521, 11160.107191424666, 11165.274257173298, 11166.553337866475, 10901.727456813564, 10974.890251097546, 10980.890191098066, 10990.890091099067, 10993.945030274877, 44330.77834610839, 44524.332135018, 44676.3298550522, 44689.10621787546, 44691.776541117084, 3887.7667339959694, 4013.478247827793, 4149.294619914622, 4260.382244574519, 4586.0, 34096.488552671726, 34139.82930085334, 34452.48321275158, 34602.307953840784, 34632.653673463195, 4322.82708691652, 4338.826446942118, 4366.061296821199, 4376.912461750779, 4421.772106362341, 363242.0, 363683.0, 365169.0, 365219.0, 366539.0, 61412.69293653503, 61697.383026170064, 61822.38177618256, 61832.38167618266, 61999.6900015497, 40050.1989960205, 41097.0, 63922.0, 63966.360336396974, 64115.35884641187, 19467.513312167026, 19546.0, 19556.413307600797, 19584.902075489532, 19626.509337266398, 13601.0, 13786.65533361654, 15034.323455444532, 15156.090634561962, 15161.393544258326, 20484.897575512026, 20770.376888693365, 20987.265445709327, 21030.579388412145, 21120.577588448145, 7355.0, 7406.629668516553, 7521.435892307989, 7719.575423351657, 7799.571023593643, 1033.3180101133216, 1055.2138656700806, 1075.1828610256262, 1090.3076546392938, 1093.136422226448, 33689.0, 34156.65843341585, 34353.0, 34575.0, 34724.47913281303, 1156.1791128298835, 1185.7806305833449, 1190.3928996211994, 1192.4276347353145, 1255.1276862580419, 17143.228554715068, 18146.364877229233, 18378.172982215834, 18379.54051148732, 18399.448016559523, 561567.0, 561687.0, 563038.0, 563412.0, 565074.0, 61333.69333153305, 61858.38141618616, 61876.0, 61920.0, 62056.06915896265, 1294.4886769725906, 1325.3903204525834, 1383.6264208663806, 1414.7312010717724, 1416.2918540729636, 31538.842305789243, 34363.4845477323, 35022.82488587541, 35043.47434788531, 35144.47283290701, 52681.736591316796, 52809.0, 52842.73578632082, 53039.46960530345, 53094.46905530895, 405383.0, 409454.0, 410569.0, 792309.0, 819973.0, 44436.333454997555, 44968.55031449644, 45112.54887451215, 45467.0, 46528.767356163, 48951.26573101335, 49320.75339623279, 49396.75301623613, 49421.752891235315, 49892.75053624854, 41829.0, 42290.57709422989, 42439.3634095495, 42994.35508467313, 43958.12083758371, 41303.7934810324, 41603.0, 41711.1657766849, 43079.7846010768, 43306.78346608247, 41843.37234941539, 41852.790736046125, 42830.78584607182, 42962.140757185305, 43053.78473107614, 27607.447851043267, 27814.582781257894, 28850.855745721976, 28901.988430404836, 29202.0, 28477.0, 28516.287092822015, 28805.0, 29028.27429314284, 29032.0], "value": 21758.3055545329, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.0952440332194, 395.3259692224755, 405.292764126599, 410.9054917369004, 416.3255526047803, 14312.069715468488, 16346.91826540867, 20112.195512179533, 20719.896400518013, 20896.477588060305, 16890.915545422286, 17289.48131556055, 17327.22027508762, 18882.150303236354, 20470.488237794063, 5196.454372290912, 5944.940550594503, 5957.880842383161, 6175.6294622322575, 6177.25872895251, 6092.51259899207, 6117.4188452097205, 6155.384461553854, 6156.2920264169525, 6193.256809182904, 5856.238688970428, 6009.188759517475, 6056.06131049688, 6111.786087486938, 6121.173641558399, 5975.820725378246, 5985.5810093293485, 5987.670678112702, 6025.668588227624, 6029.849253768669, 5101.0308041472, 5194.792208311663, 5279.524842764131, 5281.128613778722, 5290.629655924087, 24511.0, 24584.754152458427, 24880.5023899521, 24947.126850560326, 25094.0, 2582.4705935283223, 3161.604799400075, 3185.681431856796, 3250.886218982348, 3410.0281419795238, 14735.484258050918, 15727.44953926607, 15972.121533315663, 15985.840141598552, 16090.678186436206, 1960.7745109312468, 2101.77931317211, 2103.3374487036535, 2140.7003019577273, 2170.696102545645, 64463.355366446674, 64589.35410645927, 64938.675306623634, 81299.5935020327, 89064.55467722684, 10366.481675916173, 10470.0, 10654.840177397346, 10752.086072683753, 11142.888571114347, 14715.852841471662, 14734.189619571027, 14743.77884331736, 14763.77854332186, 14809.555713328617, 8513.531755753493, 8719.0, 8810.0, 8910.153535414125, 8914.777130571787, 8877.0, 8909.821803563893, 8941.642334306554, 9181.173694367539, 9190.448573085636, 6764.086848275519, 6767.2894346093935, 6778.762743303961, 6835.5898646080395, 6973.163220413481, 1783.777027871516, 1844.8985305808042, 1851.1114664960894, 1865.6175484025741, 1892.7255547945747, 997.4665146907323, 1012.4532752313816, 1033.2302434686208, 1148.121686909515, 1160.4081918221768, 20574.0, 21153.25963591267, 21593.24423645165, 21645.891770541046, 21646.567068658853, 1050.5010120193, 1077.9191560632985, 1102.6691992402248, 1120.7702421003837, 1157.085902137319, 11674.649760507198, 11926.821097683542, 12046.156769026084, 12136.332501712313, 12281.324527151093, 435829.0, 442953.0, 484903.0, 493142.0, 494127.0, 87409.0, 88547.55726221327, 91196.54401728082, 92722.53638731898, 93536.53231733797, 1058.142904247562, 1059.1950117910444, 1062.166199533378, 1067.4182570499097, 1091.3397394576325, 18458.26166953307, 18726.719099213795, 18777.530561736065, 18972.146253418352, 19225.03874806282, 41499.7925010373, 42357.788211058745, 44117.0, 47337.52662473331, 47894.76052619715, 62472.0, 62777.0, 63106.0, 63990.360096400305, 64015.0, 8657.696980605648, 8850.33622478304, 8923.375363724477, 9033.367664263438, 9198.310126740387, 13241.668958276121, 13841.238731869442, 13857.237851918242, 14057.929710351384, 14329.498467553585, 11930.10524210705, 12036.578719744766, 12459.439325230205, 12486.313252771184, 12563.560275390317, 11645.59240426581, 11679.299242045505, 12155.270683759003, 12385.318807465675, 12421.130520863453, 11823.172377933462, 11977.041836653221, 12468.563600273947, 12499.500019999461, 12521.93739031299, 8774.314787789017, 9035.367524273237, 9130.634774608941, 9173.266138709021, 9552.996935321691, 8937.195652391316, 10343.379397235889, 10455.424951627734, 10769.892301077203, 11715.0], "value": 9764.14186397614, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.6708423520465, 1484.3913995261944, 1538.7845701601775, 1667.81654018058, 1797.9910100449497, 7321.743738969137, 8492.278156356717, 8860.158284962927, 8865.423747456427, 9091.727248182566, 8860.20258176764, 8979.057198994109, 8986.910130898688, 8987.460752354864, 9002.819943601124, 2484.6645702830156, 2509.5608268553024, 2522.608995605684, 2525.444402231506, 2533.037445770605, 2474.232987773787, 2522.5207210630015, 2545.440003199293, 2550.018242976449, 2552.9872350638316, 2495.1765917247267, 2505.4112283613426, 2545.312765553309, 2546.477972015732, 2547.0703193334393, 2456.054419048661, 2456.250843492725, 2470.5800013997573, 2498.2755001049673, 2505.7243703192635, 2414.505026469587, 2419.1774796569252, 2431.610942249232, 2441.5483135619966, 2506.6240063990367, 8742.694005709833, 8888.0, 8889.644414223423, 8945.06076861933, 8948.418352807046, 5441.945580544183, 5521.4478552144465, 5562.694051827148, 5578.804741834057, 5605.2713147291, 3659.048647351681, 3687.94468082979, 3695.0, 3742.3076730804614, 3744.756590821588, 1298.2405292903663, 1313.960581182566, 1329.3220457566613, 1360.6598350412396, 1365.2968721108637, 12025.579104731292, 12099.153059285854, 12149.453274602576, 12161.148719589632, 12194.207376520586, 4286.635635970977, 4505.6846020778885, 4610.53894610543, 4625.074985003012, 4627.329037289642, 9603.087706667855, 9604.75988100303, 9608.27937904646, 9610.66362677303, 9647.951760241154, 5298.311219541435, 5298.947010529845, 5308.097623404031, 5311.362636483646, 5318.627696061239, 4105.793952922861, 4304.806283717177, 4428.357888106207, 4517.932231016537, 4967.155583550805, 3659.1766852457986, 4154.0, 4161.521425036099, 4171.561985991427, 4178.811953462102, 1180.0913296761446, 1181.3207405741562, 1195.462042081067, 1208.7824191645411, 1239.9318037507844, 928.8095940332215, 972.1687956796978, 993.1508560181086, 1008.4806324742764, 1023.4831410137833, 6123.203983482119, 6293.968530157412, 6314.242290925118, 6486.610803351719, 6622.801315960529, 505.4793562630496, 511.04434707097965, 512.0552580488992, 522.6132661830218, 542.2462776740334, 4407.206702793528, 4504.842330518416, 4524.524924882906, 4572.268437050056, 4592.88517787058, 10621.73445663849, 11138.49876755548, 11141.94429027866, 11143.665690029313, 11144.2199046066, 11061.502232399562, 11066.225364224427, 11086.501107450184, 11090.114680504334, 11100.111991040698, 7755.1469338372735, 8019.959900200462, 8029.237222463857, 8182.713605023796, 8216.137305583066, 6048.667323297261, 6181.196444462191, 6233.376662333731, 6321.618700915918, 6383.96808015957, 9661.0, 10156.746081348025, 10255.282130250811, 10331.690049298233, 10367.0, 8954.731358059269, 9903.059209375098, 10170.288079834341, 10427.270091093855, 10430.0, 7019.894701579378, 7569.713148764613, 7847.803804904923, 7854.41091918097, 8074.434789564909, 8664.436811607226, 8671.523066231417, 8696.91303086961, 8720.694775682821, 8807.0, 7863.724769633035, 7939.404544659289, 7942.285194332538, 7977.282044616012, 8144.959275203586, 7736.7678969631, 7754.883676744967, 7768.951191589289, 7846.136924938366, 7854.1360450349275, 6785.93214067853, 6855.143107111611, 6908.2400935896, 6915.0, 6951.895721564281, 5173.14643083902, 5204.869878253074, 5249.7112658804135, 5356.732163391737, 5387.353517577916, 5314.920276195938, 5404.486573775485, 5435.565154787529, 5526.751296191601, 5605.0], "value": 4677.143652960794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.79699383376135, 345.5197275786657, 345.59565308588947, 346.28837738447487, 350.4550424090538, 5347.652402593833, 5371.301730775003, 5382.677039377635, 5413.323334583177, 5438.592105592076, 5296.814611488589, 5304.151335786275, 5326.467353264681, 5337.839864804052, 5353.357597088345, 4246.982683225474, 4250.553691862357, 4278.694998025601, 4285.35719642053, 4286.957130428687, 3734.636857546989, 3971.8283106483655, 4180.0, 4229.767362795061, 4260.70175087744, 4200.768957707325, 4206.705530612859, 4210.158143253307, 4217.776844715029, 4223.704340696153, 4213.157368526292, 4231.767252801094, 4256.425382573344, 4266.743995360288, 4266.850660226908, 3610.584782749991, 3655.597884232732, 3710.684591809699, 3741.625837416264, 3755.9483344663536, 5207.802205492758, 5208.765605547759, 5213.6089793265655, 5226.712530810804, 5274.26160337553, 4851.563359297645, 4854.029194161147, 4854.417469903598, 4857.0, 4869.610431165498, 5376.569874410038, 5540.63985840919, 5555.555555555585, 5560.2493663355335, 5590.524805391547, 4140.399642051885, 4145.82344269258, 4148.087420767426, 4153.1486045360625, 4158.91682166355, 5533.391326954072, 5543.281582709333, 5879.029960056586, 5888.735006924656, 5896.086106653475, 4118.146833924746, 4163.35468002454, 4233.25917964355, 4245.596668316505, 4253.04306531028, 4303.634191093792, 4315.697901146889, 4334.609885110355, 4347.543507931685, 4349.543297953733, 4544.250198717241, 4894.486078961658, 4908.877278068006, 4917.262410638433, 4917.286993385939, 5637.210790489335, 5642.576806739511, 5662.122371032414, 5672.177534257509, 5685.459881311269, 1458.4093442155943, 1480.6668499587506, 1483.0286162563539, 1484.9234305128912, 1487.8958472906793, 920.1902325953253, 940.3887473142512, 945.4421891084156, 947.616215432737, 964.2960638733623, 4829.541193586604, 4856.611471082305, 4884.658073934862, 4888.044782087193, 4923.556879880828, 5053.343065401547, 5060.975609756053, 5064.392272927273, 5093.694378337312, 5107.208382700687, 4675.462321832964, 4832.492588278251, 4839.395075615548, 4859.829905953275, 4881.926771098437, 2682.906098286551, 2689.9058532951253, 2690.4081102157493, 2690.5157071727276, 2694.986525067362, 5143.897122057538, 5224.242484839677, 5232.462255805931, 5263.26314315996, 5269.473052694777, 4887.437944636341, 5102.285680004802, 5184.455632158644, 5187.714675692873, 5188.377394712583, 5186.988537235278, 5194.428612852583, 5210.76551555181, 5230.0, 5235.497472174004, 5023.337631055547, 5048.469910659474, 5095.974520127524, 5105.310783044241, 5127.897442051213, 4810.663253572357, 4980.0, 4989.625778066587, 5048.8990220194655, 5070.340855688737, 5223.5559977402345, 5672.829815105553, 5749.252597162342, 5825.213596164633, 5890.52875769946, 4169.60388763067, 4188.078622703, 4196.601322874322, 4206.095689426788, 4208.473940757405, 5615.7192140392, 5837.387074357302, 5841.912371314349, 5842.941570584409, 5854.736536855937, 5577.163425486209, 5605.831825045255, 5608.971955140199, 5622.212890195294, 5659.698269397979, 5594.272744543184, 5599.412061733622, 5639.830805075854, 5655.519280861173, 5657.4908258256955, 5495.835124946258, 5561.972190139023, 5623.690697011703, 5646.753116957683, 5652.604317697722, 5670.319561652628, 5673.553243922938, 5681.147827825859, 5687.544996400361, 5699.800506982236, 5552.416996215339, 5561.804212094419, 5561.972190139023, 5563.860903477445, 5601.887962240814], "value": 4180.335166982404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.3241109878801, 856.4005196362548, 890.9955450222748, 897.0040066178962, 901.9819603607924, 15724.371025158995, 16596.25316860741, 16611.41860034899, 16622.83377166228, 16662.50012499624, 16037.35850565979, 16339.509814705578, 16356.591085222875, 16357.182140892966, 16369.672606547862, 709.4466316273315, 733.2374330696069, 831.6174559702544, 858.6694122762719, 859.5315553023618, 860.6944534690158, 861.6811779641541, 861.7371701631, 861.9138086191393, 862.3920136303891, 915.4461550761794, 916.2669864108719, 917.5320586500866, 917.6100157433065, 917.6512925088457, 745.0798264143779, 765.5598031132066, 876.8246350729816, 897.4570384917097, 911.1480765484293, 669.5413641655482, 669.7655820462847, 669.9631520266383, 670.1857243449184, 670.5540815357781, 15919.0, 15981.760273595906, 16023.278952447052, 16067.678646427123, 16076.115813630246, 15560.688786224213, 15862.682746345008, 15885.36458541657, 16066.5983350416, 16277.83722162787, 13831.585052448443, 14031.228282444461, 14061.71876562463, 14463.27683615815, 14672.339744711513, 4658.650601204923, 4676.625869930397, 4770.785314660849, 4837.685550439245, 4892.094962431962, 86133.21829334197, 87836.99999999936, 89254.10745892522, 91674.5416272921, 93534.0, 11667.0, 11833.822492662444, 12051.33717645538, 12489.812652810215, 12822.935885320514, 22693.773062269494, 22798.886005569864, 22836.543269134523, 22882.970266338056, 22951.0, 8480.618372173267, 8584.613692383859, 8599.140085991476, 8670.653173872974, 9238.722838314861, 4892.339534162914, 5064.594832413398, 5224.346956630421, 5235.764390602432, 5307.3896501902, 3545.450455179451, 3671.30245253401, 3671.9632803672157, 3673.9265214696093, 3680.742348035612, 598.0, 602.1329285828393, 608.2336256317072, 611.3886113886114, 626.0484064222356, 590.7788848676184, 603.499095750526, 605.2374008749008, 607.5807692692028, 688.786476192382, 9307.0, 9415.717528474157, 9639.214826187115, 9958.0, 10289.0, 625.4621025917708, 666.5003741581442, 670.4334837062675, 672.0, 721.3039416962656, 4827.155247831615, 4909.975450122726, 4945.381827271591, 4958.281049247839, 4985.476524964826, 67321.0, 67961.3203867955, 68249.31750682529, 68749.31250687529, 130118.0, 114439.0, 115986.42006789912, 116040.0, 118816.02959926071, 119242.0, 58787.118193227136, 94473.5276323614, 98949.50525247327, 99424.0, 99839.0, 8479.618417171365, 8846.33652476054, 9176.58705358247, 9292.72121836346, 9406.576704048197, 59712.0, 59780.70109649424, 61231.08153377792, 61279.693601531704, 62157.0, 81773.59113204396, 89895.0, 90465.0, 92574.53712731393, 93010.0, 11832.526698931946, 12186.939065304618, 12282.447289872156, 12790.168639038806, 13443.663908401977, 14007.08953917992, 14044.859551404765, 14149.787753183917, 14344.641383965485, 14502.637434064232, 13100.213987160801, 13271.270080145685, 13611.795823062464, 14455.566333010027, 14919.179445130621, 13421.530246441327, 13837.584872453843, 14343.641408964859, 14366.856331436551, 14376.85623143797, 13063.0, 13085.0, 13998.650033749238, 14054.367553460323, 14180.432782688576, 11434.149062260562, 12052.216605920587, 12054.457549410123, 12265.878465794784, 12788.936055319664, 11369.374684392438, 11619.36093514831, 11813.82279265829, 11834.52661893549, 11948.880511194777], "value": 7894.787474459087, "unit": "score"}], "name": "dromaeo_css", "value": 7486.0860689692745, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 112.0, 111.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 120.0, 155.0, 128.0, 121.0, 119.0, 119.0, 149.0, 117.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 162.0, 162.0, 161.0, 164.0, 162.0, 161.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 76.0, 77.0, 78.0, 75.0, 100.0, 77.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 82.0, 82.0, 82.0, 110.0, 83.0, 83.0, 111.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 119.0, 118.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 146.0, 147.0, 145.0, 146.0, 147.0, 148.0, 148.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 49.0, 49.0, 48.0, 49.0, 48.0, 50.0, 50.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 59.0, 65.0, 60.0, 60.0, 60.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 74.0, 75.0, 83.0, 73.0, 74.0, 83.0, 73.0, 74.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 123.0, 129.0, 130.0, 118.0, 118.0, 127.0, 169.0, 121.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 155.0, 157.0, 161.0, 155.0, 158.0, 160.0, 161.0, 156.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 61.0, 61.0, 68.0, 65.0, 62.0, 61.0, 61.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1471.1, "alertThreshold": 2.0}]} 13:15:14 INFO - Return code: 0 13:15:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:15:14 INFO - # TBPL SUCCESS # 13:15:14 INFO - Running post-action listener: _resource_record_post_action 13:15:14 INFO - [mozharness: 2016-10-27 20:15:14.737391Z] Finished run-tests step (success) 13:15:14 INFO - Running post-run listener: _resource_record_post_run 13:15:14 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 359256064; Read time: 12; Write time: 284368 13:15:14 INFO - TinderboxPrint: CPU usage
13.0% 13:15:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:15:14 INFO - TinderboxPrint: I/O write bytes / time
359,256,064 / 284,368 13:15:14 INFO - TinderboxPrint: CPU idle
8,250.5 (87.0%) 13:15:14 INFO - TinderboxPrint: CPU user
1,191.7 (12.6%) 13:15:14 INFO - TinderboxPrint: Swap in / out
0 / 0 13:15:14 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107487232; Read time: 0; Write time: 100100 13:15:14 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250736640; Read time: 0; Write time: 184236 13:15:15 INFO - Running post-run listener: _upload_blobber_files 13:15:15 INFO - Blob upload gear active. 13:15:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:15:15 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 13:15:15 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'] 13:15:15 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 13:15:15 INFO - (blobuploader) - INFO - Open directory for files ... 13:15:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:15:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:15:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:18 INFO - (blobuploader) - INFO - Done attempting. 13:15:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:19 INFO - (blobuploader) - INFO - Done attempting. 13:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:20 INFO - (blobuploader) - INFO - Done attempting. 13:15:20 INFO - (blobuploader) - INFO - Iteration through files over. 13:15:20 INFO - Return code: 0 13:15:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:15:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:15:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/045feb6686d0eaf0cb9809af35ba09359ac8861ec3e38d4bcfbdbf82b50489dddbb61ef8ed0e57cdc1f1541f0d8b3b4c0272ec6b9157964cb2207f082c59d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9462c7398538345537d51d5f8a678c9268125b267bf2d6844fabeaf5aff21f02223921ff233d439667ee2ad024a0c73f3683c4ee756b30064cbe7f3cca85f291", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c6e43799a1960159bfc6b882860dc557075b9d6a4d3a48ed9ae0fba4ff58f3a3699b8ee2cc690d2f8bb48085d333712c0d01fe778551da17db4e2396e06443b4"} 13:15:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:15:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:15:20 INFO - Contents: 13:15:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/045feb6686d0eaf0cb9809af35ba09359ac8861ec3e38d4bcfbdbf82b50489dddbb61ef8ed0e57cdc1f1541f0d8b3b4c0272ec6b9157964cb2207f082c59d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9462c7398538345537d51d5f8a678c9268125b267bf2d6844fabeaf5aff21f02223921ff233d439667ee2ad024a0c73f3683c4ee756b30064cbe7f3cca85f291", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c6e43799a1960159bfc6b882860dc557075b9d6a4d3a48ed9ae0fba4ff58f3a3699b8ee2cc690d2f8bb48085d333712c0d01fe778551da17db4e2396e06443b4"} 13:15:20 INFO - Running post-run listener: copy_logs_to_upload_dir 13:15:20 INFO - Copying logs to upload dir... 13:15:20 INFO - mkdir: /builds/slave/test/build/upload/logs 13:15:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.086864 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-27 13:15:19.584524) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 13:15:19.587887) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 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/045feb6686d0eaf0cb9809af35ba09359ac8861ec3e38d4bcfbdbf82b50489dddbb61ef8ed0e57cdc1f1541f0d8b3b4c0272ec6b9157964cb2207f082c59d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9462c7398538345537d51d5f8a678c9268125b267bf2d6844fabeaf5aff21f02223921ff233d439667ee2ad024a0c73f3683c4ee756b30064cbe7f3cca85f291", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c6e43799a1960159bfc6b882860dc557075b9d6a4d3a48ed9ae0fba4ff58f3a3699b8ee2cc690d2f8bb48085d333712c0d01fe778551da17db4e2396e06443b4"} build_url:https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011489 build_url: 'https://queue.taskcluster.net/v1/task/K5zZIhoeRyqHBGMj-yM-Ow/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/045feb6686d0eaf0cb9809af35ba09359ac8861ec3e38d4bcfbdbf82b50489dddbb61ef8ed0e57cdc1f1541f0d8b3b4c0272ec6b9157964cb2207f082c59d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9462c7398538345537d51d5f8a678c9268125b267bf2d6844fabeaf5aff21f02223921ff233d439667ee2ad024a0c73f3683c4ee756b30064cbe7f3cca85f291", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c6e43799a1960159bfc6b882860dc557075b9d6a4d3a48ed9ae0fba4ff58f3a3699b8ee2cc690d2f8bb48085d333712c0d01fe778551da17db4e2396e06443b4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 13:15:19.613493) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:15:19.613983) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477598086.789360-1532878780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:15:19.698469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 13:15:19.698866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 13:15:19.699462) ========= ========= Total master_lag: 0.18 =========