builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-030 starttime: 1482945528.69 results: success (0) buildid: 20161228073121 builduid: f3356ea58fdc4506a44daca60697169f revision: a6d29e9432f5f88a941c0ea5284cb082f34bd097 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.689344) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.689809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.690084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.704851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.705281) ========= 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=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 09:18:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 82.2M=0s 2016-12-28 09:18:49 (82.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.807444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.807788) ========= 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=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021871 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:48.858381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 09:18:48.858748) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a6d29e9432f5f88a941c0ea5284cb082f34bd097 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a6d29e9432f5f88a941c0ea5284cb082f34bd097 --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=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 09:18:49,218 truncating revision to first 12 chars 2016-12-28 09:18:49,218 Setting DEBUG logging. 2016-12-28 09:18:49,218 attempt 1/10 2016-12-28 09:18:49,218 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a6d29e9432f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 09:18:49,912 unpacking tar archive at: mozilla-central-a6d29e9432f5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000638 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 09:18:49.872918) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:49.873246) ========= script_repo_revision: a6d29e9432f5f88a941c0ea5284cb082f34bd097 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:49.873665) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:49.873933) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 09:18:49.883325) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-28 09:18:49.883600) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:18:50 INFO - MultiFileLogger online at 20161228 09:18:50 in /builds/slave/test 09:18:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:18:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:18:50 INFO - {'append_to_log': False, 09:18:50 INFO - 'base_work_dir': '/builds/slave/test', 09:18:50 INFO - 'blob_upload_branch': 'Firefox', 09:18:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:18:50 INFO - 'branch': 'Firefox', 09:18:50 INFO - 'buildbot_json_path': 'buildprops.json', 09:18:50 INFO - 'config_files': ('talos/linux_config.py',), 09:18:50 INFO - 'default_actions': ('clobber', 09:18:50 INFO - 'read-buildbot-config', 09:18:50 INFO - 'download-and-extract', 09:18:50 INFO - 'populate-webroot', 09:18:50 INFO - 'create-virtualenv', 09:18:50 INFO - 'install', 09:18:50 INFO - 'run-tests'), 09:18:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:18:50 INFO - 'download_minidump_stackwalk': True, 09:18:50 INFO - 'download_symbols': 'ondemand', 09:18:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:18:50 INFO - 'tooltool.py': '/tools/tooltool.py', 09:18:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:18:50 INFO - '/tools/misc-python/virtualenv.py')}, 09:18:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:18:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:18:50 INFO - 'installer_path': 'installer.exe', 09:18:50 INFO - 'log_level': 'info', 09:18:50 INFO - 'log_name': 'talos', 09:18:50 INFO - 'log_to_console': True, 09:18:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:18:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:18:50 INFO - 'opt_config_files': (), 09:18:50 INFO - 'pip_index': False, 09:18:50 INFO - 'sps_profile': False, 09:18:50 INFO - 'sps_profile_interval': 0, 09:18:50 INFO - 'suite': 'dromaeojs', 09:18:50 INFO - 'system_bits': '32', 09:18:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:18:50 INFO - 'title': 'talos-linux64-ix-030', 09:18:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:18:50 INFO - 'use_talos_json': True, 09:18:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:18:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:18:50 INFO - 'work_dir': 'build'} 09:18:50 INFO - [mozharness: 2016-12-28 17:18:50.342709Z] Running clobber step. 09:18:50 INFO - Running pre-action listener: _resource_record_pre_action 09:18:50 INFO - Running main action method: clobber 09:18:50 INFO - rmtree: /builds/slave/test/build 09:18:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:18:51 INFO - Running post-action listener: _resource_record_post_action 09:18:51 INFO - [mozharness: 2016-12-28 17:18:51.457442Z] Finished clobber step (success) 09:18:51 INFO - [mozharness: 2016-12-28 17:18:51.457540Z] Running read-buildbot-config step. 09:18:51 INFO - Running pre-action listener: _resource_record_pre_action 09:18:51 INFO - Running main action method: read_buildbot_config 09:18:51 INFO - Using buildbot properties: 09:18:51 INFO - { 09:18:51 INFO - "project": "", 09:18:51 INFO - "product": "firefox", 09:18:51 INFO - "script_repo_revision": "production", 09:18:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:18:51 INFO - "repository": "", 09:18:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:18:51 INFO - "buildid": "20161228073121", 09:18:51 INFO - "pgo_build": "True", 09:18:51 INFO - "basedir": "/builds/slave/test", 09:18:51 INFO - "buildnumber": 142, 09:18:51 INFO - "slavename": "talos-linux64-ix-030", 09:18:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:18:51 INFO - "platform": "ubuntu64_hw", 09:18:51 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:18:51 INFO - "branch": "mozilla-central", 09:18:51 INFO - "repo_path": "mozilla-central", 09:18:51 INFO - "revision": "a6d29e9432f5f88a941c0ea5284cb082f34bd097", 09:18:51 INFO - "stage_platform": "linux64-pgo", 09:18:51 INFO - "builduid": "f3356ea58fdc4506a44daca60697169f", 09:18:51 INFO - "slavebuilddir": "test" 09:18:51 INFO - } 09:18:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:18:51 INFO - Running post-action listener: _resource_record_post_action 09:18:51 INFO - [mozharness: 2016-12-28 17:18:51.459973Z] Finished read-buildbot-config step (success) 09:18:51 INFO - [mozharness: 2016-12-28 17:18:51.460062Z] Running download-and-extract step. 09:18:51 INFO - Running pre-action listener: _resource_record_pre_action 09:18:51 INFO - Running main action method: download_and_extract 09:18:51 INFO - mkdir: /builds/slave/test/build/tests 09:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:51 INFO - trying https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:18:51 INFO - Downloading https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:18:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:18:52 INFO - Downloaded 1361 bytes. 09:18:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:18:52 INFO - Using the following test package requirements: 09:18:52 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:18:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:18:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:18:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'jsshell-linux-x86_64.zip'], 09:18:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:18:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:18:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:18:52 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:18:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:18:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:18:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:18:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:18:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:18:52 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:18:52 INFO - Expected file size: 21832030 09:18:52 INFO - Obtained file size: 21832030 09:18:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:18:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:18:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:18:56 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:18:57 INFO - Expected file size: 21832030 09:18:57 INFO - Obtained file size: 21832030 09:19:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:19:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:19:02 INFO - Fetch https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:19:02 INFO - Expected file size: 14409776 09:19:02 INFO - Obtained file size: 14409776 09:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:03 INFO - trying https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:19:03 INFO - Downloading https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:19:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:19:04 INFO - Downloaded 61254547 bytes. 09:19:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:19:04 INFO - mkdir: /builds/slave/test/properties 09:19:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:19:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:19:04 INFO - Contents: 09:19:04 INFO - build_url:https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:19:04 INFO - Running post-action listener: _resource_record_post_action 09:19:04 INFO - Running post-action listener: set_extra_try_arguments 09:19:04 INFO - [mozharness: 2016-12-28 17:19:04.974375Z] Finished download-and-extract step (success) 09:19:04 INFO - [mozharness: 2016-12-28 17:19:04.974603Z] Running populate-webroot step. 09:19:04 INFO - Running pre-action listener: _resource_record_pre_action 09:19:04 INFO - Running main action method: populate_webroot 09:19:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:19:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:19:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:19:04 INFO - '--tppagecycles', 09:19:04 INFO - '7'], 09:19:04 INFO - 'tests': ['tsvgm']}}, 09:19:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:19:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:19:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:19:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:19:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['damp', 'tps']}, 09:19:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'tests': ['damp', 'tps']}, 09:19:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['dromaeo_dom']}, 09:19:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:19:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['basic_compositor_video']}, 09:19:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:19:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['a11yr', 09:19:04 INFO - 'ts_paint', 09:19:04 INFO - 'tpaint', 09:19:04 INFO - 'sessionrestore', 09:19:04 INFO - 'sessionrestore_no_auto_restore', 09:19:04 INFO - 'tabpaint']}, 09:19:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['tsvgx', 09:19:04 INFO - 'tsvgr_opacity', 09:19:04 INFO - 'tart', 09:19:04 INFO - 'tscrollx', 09:19:04 INFO - 'cart', 09:19:04 INFO - 'tsvg_static']}, 09:19:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:19:04 INFO - 'tsvgr_opacity', 09:19:04 INFO - 'tart', 09:19:04 INFO - 'tscrollx', 09:19:04 INFO - 'cart', 09:19:04 INFO - 'tsvg_static']}, 09:19:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'talos_options': ['--disable-e10s'], 09:19:04 INFO - 'tests': ['tp5o']}, 09:19:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:19:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'talos_options': ['--disable-e10s', 09:19:04 INFO - '--xperf_path', 09:19:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:04 INFO - 'tests': ['tp5n']}, 09:19:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:19:04 INFO - 'talos_options': ['--xperf_path', 09:19:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:04 INFO - 'tests': ['tp5n']}}, 09:19:04 INFO - 'talos.zip': {'path': '', 09:19:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:19:04 INFO - Running post-action listener: _resource_record_post_action 09:19:04 INFO - [mozharness: 2016-12-28 17:19:04.996719Z] Finished populate-webroot step (success) 09:19:04 INFO - [mozharness: 2016-12-28 17:19:04.996807Z] Running create-virtualenv step. 09:19:04 INFO - Running pre-action listener: _resource_record_pre_action 09:19:04 INFO - Running main action method: create_virtualenv 09:19:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:19:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:19:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:19:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:19:05 INFO - Using real prefix '/usr' 09:19:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:19:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:19:07 INFO - Installing pip.................done. 09:19:07 INFO - Return code: 0 09:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:19:07 INFO - Reading from file tmpfile_stdout 09:19:07 INFO - Output received: 09:19:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:19:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:19:07 INFO - Using env: {'DISPLAY': ':0', 09:19:07 INFO - 'HOME': '/home/cltbld', 09:19:07 INFO - 'LANG': 'en_US.UTF-8', 09:19:07 INFO - 'LANGUAGE': 'en_US:en', 09:19:07 INFO - 'LOGNAME': 'cltbld', 09:19:07 INFO - 'MAIL': '/var/mail/cltbld', 09:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:07 INFO - 'MOZ_NO_REMOTE': '1', 09:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:07 INFO - 'NO_EM_RESTART': '1', 09:19:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:07 INFO - 'PWD': '/builds/slave/test', 09:19:07 INFO - 'SHELL': '/bin/bash', 09:19:07 INFO - 'SHLVL': '1', 09:19:07 INFO - 'TERM': 'linux', 09:19:07 INFO - 'TMOUT': '86400', 09:19:07 INFO - 'USER': 'cltbld', 09:19:07 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:07 INFO - '_': '/tools/buildbot/bin/python'} 09:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:19:08 INFO - Cleaning up... 09:19:08 INFO - Return code: 0 09:19:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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', '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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:19: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 psutil>=3.1.1 09:19:08 INFO - Using env: {'DISPLAY': ':0', 09:19:08 INFO - 'HOME': '/home/cltbld', 09:19:08 INFO - 'LANG': 'en_US.UTF-8', 09:19:08 INFO - 'LANGUAGE': 'en_US:en', 09:19:08 INFO - 'LOGNAME': 'cltbld', 09:19:08 INFO - 'MAIL': '/var/mail/cltbld', 09:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:08 INFO - 'MOZ_NO_REMOTE': '1', 09:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:08 INFO - 'NO_EM_RESTART': '1', 09:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:08 INFO - 'PWD': '/builds/slave/test', 09:19:08 INFO - 'SHELL': '/bin/bash', 09:19:08 INFO - 'SHLVL': '1', 09:19:08 INFO - 'TERM': 'linux', 09:19:08 INFO - 'TMOUT': '86400', 09:19:08 INFO - 'USER': 'cltbld', 09:19:08 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:08 INFO - '_': '/tools/buildbot/bin/python'} 09:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:08 INFO - Downloading/unpacking psutil>=3.1.1 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:10 INFO - Installing collected packages: psutil 09:19:10 INFO - Running setup.py install for psutil 09:19:10 INFO - building 'psutil._psutil_linux' extension 09:19:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:19:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:19:10 INFO - building 'psutil._psutil_posix' extension 09:19:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:19:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:10 INFO - Successfully installed psutil 09:19:10 INFO - Cleaning up... 09:19:10 INFO - Return code: 0 09:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:19: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 mozsystemmonitor==0.3 09:19:10 INFO - Using env: {'DISPLAY': ':0', 09:19:10 INFO - 'HOME': '/home/cltbld', 09:19:10 INFO - 'LANG': 'en_US.UTF-8', 09:19:10 INFO - 'LANGUAGE': 'en_US:en', 09:19:10 INFO - 'LOGNAME': 'cltbld', 09:19:10 INFO - 'MAIL': '/var/mail/cltbld', 09:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:10 INFO - 'MOZ_NO_REMOTE': '1', 09:19:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:10 INFO - 'NO_EM_RESTART': '1', 09:19:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:10 INFO - 'PWD': '/builds/slave/test', 09:19:10 INFO - 'SHELL': '/bin/bash', 09:19:10 INFO - 'SHLVL': '1', 09:19:10 INFO - 'TERM': 'linux', 09:19:10 INFO - 'TMOUT': '86400', 09:19:10 INFO - 'USER': 'cltbld', 09:19:10 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:10 INFO - '_': '/tools/buildbot/bin/python'} 09:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:19:13 INFO - Installing collected packages: mozsystemmonitor 09:19:13 INFO - Running setup.py install for mozsystemmonitor 09:19:13 INFO - Successfully installed mozsystemmonitor 09:19:13 INFO - Cleaning up... 09:19:13 INFO - Return code: 0 09:19:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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', '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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:19: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 jsonschema==2.5.1 09:19:13 INFO - Using env: {'DISPLAY': ':0', 09:19:13 INFO - 'HOME': '/home/cltbld', 09:19:13 INFO - 'LANG': 'en_US.UTF-8', 09:19:13 INFO - 'LANGUAGE': 'en_US:en', 09:19:13 INFO - 'LOGNAME': 'cltbld', 09:19:13 INFO - 'MAIL': '/var/mail/cltbld', 09:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:13 INFO - 'MOZ_NO_REMOTE': '1', 09:19:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:13 INFO - 'NO_EM_RESTART': '1', 09:19:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:13 INFO - 'PWD': '/builds/slave/test', 09:19:13 INFO - 'SHELL': '/bin/bash', 09:19:13 INFO - 'SHLVL': '1', 09:19:13 INFO - 'TERM': 'linux', 09:19:13 INFO - 'TMOUT': '86400', 09:19:13 INFO - 'USER': 'cltbld', 09:19:13 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:13 INFO - '_': '/tools/buildbot/bin/python'} 09:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:19:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:19:15 INFO - warning: no files found matching '*.txt' 09:19:15 INFO - no previously-included directories found matching 'build' 09:19:15 INFO - no previously-included directories found matching 'dist' 09:19:15 INFO - no previously-included directories found matching '.git*' 09:19:15 INFO - Installing collected packages: jsonschema, functools32 09:19:15 INFO - Running setup.py install for functools32 09:19:15 INFO - warning: no files found matching '*.txt' 09:19:15 INFO - no previously-included directories found matching 'build' 09:19:15 INFO - no previously-included directories found matching 'dist' 09:19:15 INFO - no previously-included directories found matching '.git*' 09:19:15 INFO - Successfully installed jsonschema functools32 09:19:15 INFO - Cleaning up... 09:19:16 INFO - Return code: 0 09:19:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:19:16 INFO - Using env: {'DISPLAY': ':0', 09:19:16 INFO - 'HOME': '/home/cltbld', 09:19:16 INFO - 'LANG': 'en_US.UTF-8', 09:19:16 INFO - 'LANGUAGE': 'en_US:en', 09:19:16 INFO - 'LOGNAME': 'cltbld', 09:19:16 INFO - 'MAIL': '/var/mail/cltbld', 09:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:16 INFO - 'MOZ_NO_REMOTE': '1', 09:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:16 INFO - 'NO_EM_RESTART': '1', 09:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:16 INFO - 'PWD': '/builds/slave/test', 09:19:16 INFO - 'SHELL': '/bin/bash', 09:19:16 INFO - 'SHLVL': '1', 09:19:16 INFO - 'TERM': 'linux', 09:19:16 INFO - 'TMOUT': '86400', 09:19:16 INFO - 'USER': 'cltbld', 09:19:16 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:16 INFO - '_': '/tools/buildbot/bin/python'} 09:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:19:16 INFO - Cleaning up... 09:19:16 INFO - Return code: 0 09:19:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:19:16 INFO - Using env: {'DISPLAY': ':0', 09:19:16 INFO - 'HOME': '/home/cltbld', 09:19:16 INFO - 'LANG': 'en_US.UTF-8', 09:19:16 INFO - 'LANGUAGE': 'en_US:en', 09:19:16 INFO - 'LOGNAME': 'cltbld', 09:19:16 INFO - 'MAIL': '/var/mail/cltbld', 09:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:16 INFO - 'MOZ_NO_REMOTE': '1', 09:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:16 INFO - 'NO_EM_RESTART': '1', 09:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:16 INFO - 'PWD': '/builds/slave/test', 09:19:16 INFO - 'SHELL': '/bin/bash', 09:19:16 INFO - 'SHLVL': '1', 09:19:16 INFO - 'TERM': 'linux', 09:19:16 INFO - 'TMOUT': '86400', 09:19:16 INFO - 'USER': 'cltbld', 09:19:16 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:16 INFO - '_': '/tools/buildbot/bin/python'} 09:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:19:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:19:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:19 INFO - Downloading docopt-0.6.1.tar.gz 09:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:19:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:19:19 INFO - Running setup.py install for blobuploader 09:19:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:19:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:19:19 INFO - Running setup.py install for requests 09:19:19 INFO - Running setup.py install for docopt 09:19:19 INFO - Successfully installed blobuploader requests docopt 09:19:19 INFO - Cleaning up... 09:19:19 INFO - Return code: 0 09:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:19 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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:19:19 INFO - Using env: {'DISPLAY': ':0', 09:19:19 INFO - 'HOME': '/home/cltbld', 09:19:19 INFO - 'LANG': 'en_US.UTF-8', 09:19:19 INFO - 'LANGUAGE': 'en_US:en', 09:19:19 INFO - 'LOGNAME': 'cltbld', 09:19:19 INFO - 'MAIL': '/var/mail/cltbld', 09:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:19 INFO - 'MOZ_NO_REMOTE': '1', 09:19:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:19 INFO - 'NO_EM_RESTART': '1', 09:19:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:19 INFO - 'PWD': '/builds/slave/test', 09:19:19 INFO - 'SHELL': '/bin/bash', 09:19:19 INFO - 'SHLVL': '1', 09:19:19 INFO - 'TERM': 'linux', 09:19:19 INFO - 'TMOUT': '86400', 09:19:19 INFO - 'USER': 'cltbld', 09:19:19 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:19 INFO - '_': '/tools/buildbot/bin/python'} 09:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:20 INFO - Running setup.py (path:/tmp/pip-aCoZUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:20 INFO - Running setup.py (path:/tmp/pip-mn7kzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:20 INFO - Running setup.py (path:/tmp/pip-7ZKGb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:20 INFO - Running setup.py (path:/tmp/pip-rO4KY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:20 INFO - Running setup.py (path:/tmp/pip-yJx4PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:20 INFO - Running setup.py (path:/tmp/pip-VpJl6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:20 INFO - Running setup.py (path:/tmp/pip-zEahdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:20 INFO - Running setup.py (path:/tmp/pip-BhGTB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:20 INFO - Running setup.py (path:/tmp/pip-G1o4G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:20 INFO - Running setup.py (path:/tmp/pip-ETTIHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:20 INFO - Running setup.py (path:/tmp/pip-JpriX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:20 INFO - Running setup.py (path:/tmp/pip-EPXFyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:20 INFO - Running setup.py (path:/tmp/pip-ZynNBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:21 INFO - Running setup.py (path:/tmp/pip-ckjJjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:21 INFO - Running setup.py (path:/tmp/pip-FaCFaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:21 INFO - Running setup.py (path:/tmp/pip-vq4Jtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:21 INFO - Running setup.py (path:/tmp/pip-CcwHx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:19:21 INFO - Running setup.py install for manifestparser 09:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:19:21 INFO - Running setup.py install for mozcrash 09:19:21 INFO - Running setup.py install for mozdebug 09:19:21 INFO - Running setup.py install for mozdevice 09:19:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:19:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:19:21 INFO - Running setup.py install for mozfile 09:19:21 INFO - Running setup.py install for mozhttpd 09:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:19:21 INFO - Running setup.py install for mozinfo 09:19:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for mozInstall 09:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for mozleak 09:19:22 INFO - Running setup.py install for mozlog 09:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for moznetwork 09:19:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for mozprocess 09:19:22 INFO - Running setup.py install for mozprofile 09:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for mozrunner 09:19:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:22 INFO - Running setup.py install for mozscreenshot 09:19:22 INFO - Running setup.py install for moztest 09:19:23 INFO - Running setup.py install for mozversion 09:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:23 INFO - Cleaning up... 09:19:23 INFO - Return code: 0 09:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:23 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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:19:23 INFO - Using env: {'DISPLAY': ':0', 09:19:23 INFO - 'HOME': '/home/cltbld', 09:19:23 INFO - 'LANG': 'en_US.UTF-8', 09:19:23 INFO - 'LANGUAGE': 'en_US:en', 09:19:23 INFO - 'LOGNAME': 'cltbld', 09:19:23 INFO - 'MAIL': '/var/mail/cltbld', 09:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:23 INFO - 'MOZ_NO_REMOTE': '1', 09:19:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:23 INFO - 'NO_EM_RESTART': '1', 09:19:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:23 INFO - 'PWD': '/builds/slave/test', 09:19:23 INFO - 'SHELL': '/bin/bash', 09:19:23 INFO - 'SHLVL': '1', 09:19:23 INFO - 'TERM': 'linux', 09:19:23 INFO - 'TMOUT': '86400', 09:19:23 INFO - 'USER': 'cltbld', 09:19:23 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:23 INFO - '_': '/tools/buildbot/bin/python'} 09:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:23 INFO - Running setup.py (path:/tmp/pip-l3vHK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:23 INFO - Running setup.py (path:/tmp/pip-oojVfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:23 INFO - Running setup.py (path:/tmp/pip-yquXPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:23 INFO - Running setup.py (path:/tmp/pip-UKFmyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:23 INFO - Running setup.py (path:/tmp/pip-tS8Uej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:23 INFO - Running setup.py (path:/tmp/pip-BJWU7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:23 INFO - Running setup.py (path:/tmp/pip-TAqd_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:23 INFO - Running setup.py (path:/tmp/pip-NbMfHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:23 INFO - Running setup.py (path:/tmp/pip-hDZI3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:24 INFO - Running setup.py (path:/tmp/pip-7PzS0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:24 INFO - Running setup.py (path:/tmp/pip-8swgw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:24 INFO - Running setup.py (path:/tmp/pip-DKwiLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:24 INFO - Running setup.py (path:/tmp/pip-N7_yKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:24 INFO - Running setup.py (path:/tmp/pip-e0BxTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:24 INFO - Running setup.py (path:/tmp/pip-LtXIN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:24 INFO - Running setup.py (path:/tmp/pip-QNyZOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:24 INFO - Running setup.py (path:/tmp/pip-jB73oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:24 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:24 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:26 INFO - Downloading blessings-1.6.tar.gz 09:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:26 INFO - Installing collected packages: blessings 09:19:26 INFO - Running setup.py install for blessings 09:19:26 INFO - Successfully installed blessings 09:19:26 INFO - Cleaning up... 09:19:26 INFO - Return code: 0 09:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:27 INFO - Reading from file tmpfile_stdout 09:19:27 INFO - Current package versions: 09:19:27 INFO - argparse == 1.2.1 09:19:27 INFO - blessings == 1.6 09:19:27 INFO - blobuploader == 1.2.4 09:19:27 INFO - docopt == 0.6.1 09:19:27 INFO - functools32 == 3.2.3-2 09:19:27 INFO - jsonschema == 2.5.1 09:19:27 INFO - manifestparser == 1.1 09:19:27 INFO - mozInstall == 1.12 09:19:27 INFO - mozcrash == 1.0 09:19:27 INFO - mozdebug == 0.1 09:19:27 INFO - mozdevice == 0.49 09:19:27 INFO - mozfile == 1.2 09:19:27 INFO - mozhttpd == 0.7 09:19:27 INFO - mozinfo == 0.9 09:19:27 INFO - mozleak == 0.1 09:19:27 INFO - mozlog == 3.4 09:19:27 INFO - moznetwork == 0.27 09:19:27 INFO - mozprocess == 0.23 09:19:27 INFO - mozprofile == 0.28 09:19:27 INFO - mozrunner == 6.13 09:19:27 INFO - mozscreenshot == 0.1 09:19:27 INFO - mozsystemmonitor == 0.3 09:19:27 INFO - moztest == 0.7 09:19:27 INFO - mozversion == 1.4 09:19:27 INFO - psutil == 3.1.1 09:19:27 INFO - requests == 1.2.3 09:19:27 INFO - wsgiref == 0.1.2 09:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:27 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 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:19:27 INFO - Using env: {'DISPLAY': ':0', 09:19:27 INFO - 'HOME': '/home/cltbld', 09:19:27 INFO - 'LANG': 'en_US.UTF-8', 09:19:27 INFO - 'LANGUAGE': 'en_US:en', 09:19:27 INFO - 'LOGNAME': 'cltbld', 09:19:27 INFO - 'MAIL': '/var/mail/cltbld', 09:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:27 INFO - 'MOZ_NO_REMOTE': '1', 09:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:27 INFO - 'NO_EM_RESTART': '1', 09:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:27 INFO - 'PWD': '/builds/slave/test', 09:19:27 INFO - 'SHELL': '/bin/bash', 09:19:27 INFO - 'SHLVL': '1', 09:19:27 INFO - 'TERM': 'linux', 09:19:27 INFO - 'TMOUT': '86400', 09:19:27 INFO - 'USER': 'cltbld', 09:19:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:27 INFO - '_': '/tools/buildbot/bin/python'} 09:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:19:27 INFO - Cleaning up... 09:19:27 INFO - Return code: 0 09:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2842c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, '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': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:19:27 INFO - Using env: {'DISPLAY': ':0', 09:19:27 INFO - 'HOME': '/home/cltbld', 09:19:27 INFO - 'LANG': 'en_US.UTF-8', 09:19:27 INFO - 'LANGUAGE': 'en_US:en', 09:19:27 INFO - 'LOGNAME': 'cltbld', 09:19:27 INFO - 'MAIL': '/var/mail/cltbld', 09:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:27 INFO - 'MOZ_NO_REMOTE': '1', 09:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:27 INFO - 'NO_EM_RESTART': '1', 09:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:27 INFO - 'PWD': '/builds/slave/test', 09:19:27 INFO - 'SHELL': '/bin/bash', 09:19:27 INFO - 'SHLVL': '1', 09:19:27 INFO - 'TERM': 'linux', 09:19:27 INFO - 'TMOUT': '86400', 09:19:27 INFO - 'USER': 'cltbld', 09:19:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:27 INFO - '_': '/tools/buildbot/bin/python'} 09:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:19:27 INFO - Cleaning up... 09:19:27 INFO - Return code: 0 09:19:27 INFO - Running post-action listener: _resource_record_post_action 09:19:27 INFO - Running post-action listener: _start_resource_monitoring 09:19:27 INFO - Starting resource monitoring. 09:19:27 INFO - [mozharness: 2016-12-28 17:19:27.589034Z] Finished create-virtualenv step (success) 09:19:27 INFO - [mozharness: 2016-12-28 17:19:27.589580Z] Running install step. 09:19:27 INFO - Running pre-action listener: _resource_record_pre_action 09:19:27 INFO - Running main action method: install 09:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:27 INFO - Reading from file tmpfile_stdout 09:19:27 INFO - Detecting whether we're running mozinstall >=1.0... 09:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:19:27 INFO - Reading from file tmpfile_stdout 09:19:27 INFO - Output received: 09:19:27 INFO - Usage: mozinstall [options] installer 09:19:27 INFO - Options: 09:19:27 INFO - -h, --help show this help message and exit 09:19:27 INFO - -d DEST, --destination=DEST 09:19:27 INFO - Directory to install application into. [default: 09:19:27 INFO - "/builds/slave/test"] 09:19:27 INFO - --app=APP Application being installed. [default: firefox] 09:19:27 INFO - mkdir: /builds/slave/test/build/application 09:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:19:45 INFO - Reading from file tmpfile_stdout 09:19:45 INFO - Output received: 09:19:45 INFO - /builds/slave/test/build/application/firefox/firefox 09:19:45 INFO - Running post-action listener: _resource_record_post_action 09:19:45 INFO - [mozharness: 2016-12-28 17:19:45.187205Z] Finished install step (success) 09:19:45 INFO - [mozharness: 2016-12-28 17:19:45.187450Z] Running run-tests step. 09:19:45 INFO - Running pre-action listener: _resource_record_pre_action 09:19:45 INFO - Running main action method: run_tests 09:19:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:45 INFO - Python 2.7.3 09:19:45 INFO - Return code: 0 09:19:45 INFO - grabbing minidump binary from tooltool 09:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:45 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 0x28403f0>, '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 0x2840cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28411a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:19:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:19:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:19:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:45 INFO - Return code: 0 09:19:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:19:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:19:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:19:45 INFO - Using env: {'DISPLAY': ':0', 09:19:45 INFO - 'HOME': '/home/cltbld', 09:19:45 INFO - 'LANG': 'en_US.UTF-8', 09:19:45 INFO - 'LANGUAGE': 'en_US:en', 09:19:45 INFO - 'LOGNAME': 'cltbld', 09:19:45 INFO - 'MAIL': '/var/mail/cltbld', 09:19:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:45 INFO - 'MOZ_NO_REMOTE': '1', 09:19:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:45 INFO - 'NO_EM_RESTART': '1', 09:19:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:45 INFO - 'PWD': '/builds/slave/test', 09:19:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:45 INFO - 'SHELL': '/bin/bash', 09:19:45 INFO - 'SHLVL': '1', 09:19:45 INFO - 'TERM': 'linux', 09:19:45 INFO - 'TMOUT': '86400', 09:19:45 INFO - 'USER': 'cltbld', 09:19:45 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796', 09:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:45 INFO - '_': '/tools/buildbot/bin/python'} 09:19:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:19:45 INFO - mozversion application_buildid: 20161228073121 09:19:45 INFO - mozversion application_changeset: a6d29e9432f5f88a941c0ea5284cb082f34bd097 09:19:45 INFO - mozversion application_display_name: Nightly 09:19:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:45 INFO - mozversion application_name: Firefox 09:19:45 INFO - mozversion application_remotingname: firefox 09:19:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:19:45 INFO - mozversion application_vendor: Mozilla 09:19:45 INFO - mozversion application_version: 53.0a1 09:19:45 INFO - mozversion platform_buildid: 20161228073121 09:19:45 INFO - mozversion platform_changeset: a6d29e9432f5f88a941c0ea5284cb082f34bd097 09:19:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:19:45 INFO - mozversion platform_version: 53.0a1 09:19:45 INFO - using testdate: 1482945585 09:19:45 INFO - actual date: 1482945585 09:19:45 INFO - starting webserver on 'localhost:58473' 09:19:45 INFO - SUITE-START | Running 2 tests 09:19:45 INFO - TEST-START | dromaeo_css 09:19:45 INFO - operating with platform_type : linux_ 09:19:45 INFO - Initialising browser for dromaeo_css test... 09:19:45 INFO - TEST-INFO | started process 8047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYoLPzZ/profile http://localhost:58473/getInfo.html) 09:19:45 INFO - PROCESS | 8047 | 1482945585948 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:19:49 INFO - PROCESS | 8047 | __metrics Screen width/height:1600/1200 09:19:49 INFO - PROCESS | 8047 | colorDepth:24 09:19:49 INFO - PROCESS | 8047 | Browser inner width/height: 1024/697 09:19:49 INFO - PROCESS | 8047 | __metrics 09:19:49 INFO - PROCESS | 8047 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:19:49 INFO - PROCESS | 8047 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:50 INFO - PROCESS | 8047 | console.error: 09:19:50 INFO - PROCESS | 8047 | Message: Error: Connection closed before committing the transaction. 09:19:50 INFO - PROCESS | 8047 | Stack: 09:19:50 INFO - PROCESS | 8047 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 09:19:50 INFO - PROCESS | 8047 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 09:19:50 INFO - PROCESS | 8047 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 09:19:50 INFO - PROCESS | 8047 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 09:19:50 INFO - PROCESS | 8047 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 09:19:50 INFO - PROCESS | 8047 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 09:19:50 INFO - PROCESS | 8047 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 09:19:50 INFO - PROCESS | 8047 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 09:19:50 INFO - PROCESS | 8047 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 09:19:50 INFO - PROCESS | 8047 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 09:19:50 INFO - PROCESS | 8047 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 09:19:50 INFO - PROCESS | 8047 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 09:19:50 INFO - PROCESS | 8047 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 09:19:50 INFO - PROCESS | 8047 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 09:19:50 INFO - PROCESS | 8047 | 09:19:50 INFO - PROCESS | 8047 | console.error: PushService: 09:19:50 INFO - PROCESS | 8047 | stateChangeProcessEnqueue: Error transitioning state 09:19:50 INFO - PROCESS | 8047 | UnknownError 09:19:51 INFO - TEST-INFO | 8047: exit 0 09:19:51 INFO - Browser initialized. 09:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:51 INFO - TEST-INFO | started process 8129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYoLPzZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:03 INFO - PROCESS | 8129 | RSS: Main: 151592960 09:20:03 INFO - PROCESS | 8129 | 09:23:04 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58473/tests/dromaeo/cssquery-ext.html) 09:23:06 INFO - PROCESS | 8129 | RSS: Main: 358252544 09:23:06 INFO - PROCESS | 8129 | 09:24:52 INFO - PROCESS | 8129 | ************************* 09:24:52 INFO - PROCESS | 8129 | A coding exception was thrown and uncaught in a Task. 09:24:52 INFO - PROCESS | 8129 | 09:24:52 INFO - PROCESS | 8129 | Full message: ReferenceError: fetch is not defined 09:24:52 INFO - PROCESS | 8129 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:24:52 INFO - PROCESS | 8129 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:24:52 INFO - PROCESS | 8129 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:24:52 INFO - PROCESS | 8129 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:24:52 INFO - PROCESS | 8129 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:24:52 INFO - PROCESS | 8129 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:24:52 INFO - PROCESS | 8129 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:24:52 INFO - PROCESS | 8129 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:24:52 INFO - PROCESS | 8129 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:24:52 INFO - PROCESS | 8129 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:24:52 INFO - PROCESS | 8129 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:24:52 INFO - PROCESS | 8129 | 09:24:52 INFO - PROCESS | 8129 | ************************* 09:26:08 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-ext.html (next: http://localhost:58473/tests/dromaeo/cssquery-jquery.html) 09:26:09 INFO - PROCESS | 8129 | RSS: Main: 243236864 09:26:09 INFO - PROCESS | 8129 | 09:29:11 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58473/tests/dromaeo/cssquery-mootools.html) 09:29:12 INFO - PROCESS | 8129 | RSS: Main: 249950208 09:29:12 INFO - PROCESS | 8129 | 09:32:14 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58473/tests/dromaeo/cssquery-prototype.html) 09:32:14 INFO - PROCESS | 8129 | RSS: Main: 184524800 09:32:14 INFO - PROCESS | 8129 | 09:35:17 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58473/tests/dromaeo/cssquery-yui.html) 09:35:18 INFO - PROCESS | 8129 | RSS: Main: 185872384 09:35:18 INFO - PROCESS | 8129 | 09:38:20 INFO - PROCESS | 8129 | Cycle 1(1): loaded http://localhost:58473/tests/dromaeo/cssquery-yui.html (next: http://localhost:58473/tests/dromaeo/cssquery-dojo.html) 09:38:21 INFO - PROCESS | 8129 | RSS: Main: 279437312 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | __start_tp_report 09:38:21 INFO - PROCESS | 8129 | _x_x_mozilla_page_load 09:38:21 INFO - PROCESS | 8129 | _x_x_mozilla_page_load_details 09:38:21 INFO - PROCESS | 8129 | |i|pagename|runs| 09:38:21 INFO - PROCESS | 8129 | |0;dojo.html;3118.2827949571597;3148.8425578721058;3154.258749193939;3181.968180318196;3203.135153508553;5258.158694608864;5307.150855863063;5312.734363281835;5385.649932754368;5421.241026256323;5382.2464854920245;5393.056215162341;5408.134698448249;5408.242846001563;5423.159410291411;4801.951980480203;4804.615630749549;4818.036392721469;4854.34466347044;4900.264960255972;4767.880802979936;4790.736509491977;4801.423829140508;4805.951940480585;4810.951890481086;4809.374781278429;4811.061842940628;4811.061842940628;4813.951860481403;4817.132916075105;4798.952010479903;4812.37439132914;4812.879678008043;4815.975920120412;4816.579109162771;4792.472827988919;4798.568128868384;4802.471728109905;4805.014971930746;4819.253015782524;5298.841034768963;5370;5441.809536666237;5506.311711036121;5510.448955104497;5591.7483713233005;5807.419258074201;5810.041343178371;5813.098969659667;5859.091840764689;5688.033034384164;5726.971365143189;5733.82798516041;5772.9711351442975;5786.161006654053;5380.219868119101;5396.946030539723;5400.432954539756;5404.567634589223;5406.594505412031;5763.538916886639;5882.941170588239;5884.705764711747;5893.469587737039;5894.6757928314355;5587.776488940478;5636.351819540805;5675.2054712340305;5681.289838770153;5744.971275143597;5815.3021637402935;5820.970895145497;5823.068309070529;5823.359430462646;5825.38833422493;5761.39505351932;5763.510101641324;5777;5780.942190578125;5783.537317014629;5517.062099443105;5559.416261292506;5647.689377084218;5655.349634792052;5655.943440565624;4261.637760790368;4274.187904298174;4279.101388708344;4279.40088387626;4312.504062032906;3793.393057110849;3798.430235464647;3800.1259710266795;3801.752886062397;3840.1935593525113;5351.678899266057;5401.4868587484125;5422.376426710979;5440.483154100433;5464.153056276284;5811.593188476766;5820.126980952891;5822.534197264209;5835.066389377764;5872.060470324727;5481.479259470344;5491.478309560585;5507.779688812483;5507.972460137754;5510.421405752419;4676.111538807616;4687.507811679793;4730.929036064462;4769.833055843098;4774.665773395829;5884.117382392504;5887.20522729426;5890.4698577128265;5944.108383742473;5951.613145145551;5624.128260119688;5626.212330273683;5629.521490673339;5633.436656334334;5636.154576813511;5762.1644861494015;5786.768529258951;5889.52883769306;5889.793857214977;6003.939960600513;5719.399463056319;5783.016887129114;5813.738381772915;5816.2438882946635;5819.301683797802;5857.7949771757785;5859.589828711949;5861.853453663693;5864.648121112747;5865.090910908816;5647.971760141173;5707.286589176353;5712.11462223356;5713.200151978644;5715.085586306171;5534.03154447962;5575.414581468887;5577.721113944205;5660.20757093999;5663.2071509989455;5573.052581061311;5620.747066382104;5620.887582248414;5621.606487545832;5637.379888212211;5681.431856814285;5685.090385538294;5691.8861622768145;5692.686902220417;5693.800716975052;5542.584306177134;5545.306836645419;5547.057000310037;5548.778048878161;5550.500454959073 09:38:21 INFO - PROCESS | 8129 | |1;ext.html;572.4304317204382;575.1975993489083;585.2099665451643;602.3795490644635;608.6074481959139;38836.223275534474;41589;41692.791536042314;45973.31040034394;46026.76986615062;43314.566854331446;43775.56224437755;43804.78097609508;43810;43822.78088609553;8199.467034642728;8405.033421156584;8622.310215182786;8799.076097009818;8800.471971681687;10103.646372376968;10396.220283478731;10418.791624167512;10728.946355268212;10731.570737170503;10602.946985265102;10684.19868509861;10686.946565267162;10692.732681682943;10694.893051069468;10534.157267418626;10601.04590586847;10611.522481488351;10612.204084693602;10612.20408469368;10421.68734937953;10475.895241047569;10495.475226238657;10500.317479363817;10501.894981050169;40738;40897.386539201936;41038.589614103774;41053.58946410527;41061.589384106075;4086.9386959195635;4157.397177409259;4170.874873753762;4244.427002354704;4267.679924005712;31094.222644433845;31170.688293117004;31566.36867262665;31855.203619909455;32453;3639.5450568678916;3829.061879839457;3880.611938806125;3881.2237552489332;3911.3155197840547;333516;336580;338910;339390;339708;50533.494665053615;51006.234906476435;51082.489175108516;51085.48914510882;51106.23340649893;52869;53131.20303195455;53233.467665323624;53241;53254.46745532495;14777.187254701095;15181.316840742193;15191.620209494851;15201.695966080837;15204.391824327124;10343.741406464747;10634.202434817265;10885.83671244932;11934.940325298317;11976.700582485508;15926.362945482282;16269.267882945296;16272.75590866138;16290.266937987819;16296.592585185228;6530.183727034121;6603.636799976048;6678.799636010927;6679;6709.664516774142;891.9063498332582;898.7169041751828;911.9361644684941;917.5228880981851;943.0899182289124;29854;30640;30662;31088.533671994937;31132.53301200484;1051.1905832508926;1063.6596289187542;1079.9622013229657;1083.6099004358425;1093.6445655162104;15582.298796554182;16144.838551614333;16278;16331.918340408221;16633.168341582874;497710;504364;507128;508618;509613;56527.43472565304;57440.71279643575;57722;57764.4223557759;57809.132863007086;1348.7504811609883;1387.8473367929519;1396.4623619906206;1397.0709478196886;1398.216998480854;28298.71701283043;29414;29840.552391714577;29976.452001579237;30414.239644009078;47759.761201193774;48537;48550.5144948546;48635.75682121567;48637.27044094271;422220;424650;424743;424769;424995;23555.41111472227;24307;25443.491130177663;26549.203523894314;26888.731112688623;45418.09163816771;45854.31218531658;45894.541054590365;45972.31041534313;46053;38155.2368952625;38777.6122238774;39070.4139437903;40054.39918401168;40416.19167616573;38435.6156438432;38685.80657096697;38756.80621596874;38799.6120038796;39150.6084939147;37967.240655186186;38454.61545384622;38897.611023889396;39142.412863806494;39152.21695566016;31772;33911.152221194665;34384.31231375408;34555.65444345625;34859;26109.216723498324;26142.73857261403;26184;26491.65151921519;26689.466210676066 09:38:21 INFO - PROCESS | 8129 | |2;jquery.html;393.67718470853913;399.8900302416835;402.72614622057006;406.4756464161232;426.73968878983834;17299.913500432496;17701.557461063447;18618.813811861877;18757.906210468962;18761.249550018;17991.19039643216;18077.63844723105;18131.274749010056;18202;18281.085945702696;5521.861953451165;5559.054960656698;5646.18130371096;5676.489115979561;5737.598368114233;5638.351589567191;5650.180723795047;5683.488486036256;5687.800926967557;5689.687067211302;5442.482964118402;5578.44215578441;5613.943860561403;5693.544516438675;5696.8860622787515;5591.384947655775;5609.298837645294;5628.296462942132;5638.492535671769;5648.63283886546;5070.873228169288;5115.641905066647;5326.547243484309;5365.382980957162;5419.810306639288;11904.285742855456;11919.582814601534;11971.162018658697;12113;12123.090768192333;2798.3563780330433;2987.925301867449;3014.9698503014906;3044.3759029399143;3085.460044492205;9865.654702085392;9913.206943444578;9944.453055081967;10214.438205898672;10528.736781580521;1872.513146581885;1924.9133788979389;1929.778075521305;1944.2611807513197;1973.5756812285283;73446.63276683583;75065.62467187684;75093;75533.62233188853;76139.85790213206;9048;9530.332876698565;9689.467079310565;10770.946145269223;10933.781324373627;12938;12977.545785897448;13025.934870325587;13034.41345139471;13054.869451305365;7321.560706357529;7326.084239470067;7505.549667019996;7652.311291983749;7670.654820533089;7318.597477138808;7359.889601655979;7532.698692052366;7538.773836784905;7545.886811697829;4025.7383270087353;4072.775997320176;4097;4168.7707176105605;4169.728967617095;1988.6818109102473;2031.349968010252;2043.7138800567932;2060.939504835348;2068.7414073240843;859.9551544872884;867.9566021698889;869.3523325122698;887.7736177274753;913.3058875254721;16823.495295141165;16912;17458.738118928228;17652.205650745746;17704.734428983575;858.553552152877;860.6557377049105;866.2290561400358;870.3124531619949;887.3522328700085;9035.63857445708;9664.371815831948;9982.251331150193;10037.498125093716;10190.184785217165;348366;350353;351833;412286;413161;73888.63055684687;75046;75937;80286.59856700679;88095.55952220326;1022.7187523431116;1022.9744256393501;1067.5622994572188;1069.1553672598716;1070.0850772589479;15974;16404.835951640332;16540.503784886474;16803.66392672164;16982.320707171573;38100.809495952344;38710;38825.41761873518;38908.80545597254;39065.609343906195;50768;53100.2034969468;53141.7342913283;55029.89940201254;55357.723211385295;8359.498430094214;8596.656133754579;8641.783955401166;8659.653613855375;8660.09069047741;12460.501579936701;12534.310612916377;12596.685082873002;12641.178323408949;12786.61640150797;10312.948435257777;11182.496787644739;11209.663710088711;11658.76682466329;11891.28652280866;10289.2797504174;10466.842997354894;10624.512568240607;10701.678949631523;11195.216334856483;11052.502637381176;11082.16883733707;11107.500162492546;11167.329960202414;11346.829797552879;8346.958265208636;8494.830103397773;8511;8618.655253789779;8811.60347784364;11005.449727513753;11176.10591152722;11249.212555121063;11370.374629395463;11568.24806387582 09:38:21 INFO - PROCESS | 8129 | |3;mootools.html;1286.421110500275;1354.9119307245028;1358.225811287566;1363.4546181527392;1367.357342049237;6789.592624442531;7495.887561686573;7574.166841647421;7680.654370553325;8111;7960.76117716468;8019.318357939582;8039.718609848656;8045.637946292416;8045.758627241177;1925.3646296722118;2210.999999999996;2246.3822448826622;2247.9775202248015;2248.5615305015526;2014.979850201494;2028.8579799414047;2031.7460317460318;2124.2352753008904;2201.3726088064905;2203.030666506732;2204.5039866030097;2207.75714671386;2208.4540821425066;2208.9889550552225;1934.7388102606108;1946.0172612830502;1950.1711772496774;1954.3257576136211;1957.2366776956999;2118.195085867376;2175.945601359963;2190.69330293759;2193.517426166241;2194.9561008779897;7946.801329966739;7990.880136797953;8019.799505012363;8020.799480012988;8025.23760242776;4782.760861956888;4913.459519452857;4930.28510865926;4936.358273424432;4938.678985865908;3087.4133914556164;3110.0825256549147;3167.192365946692;3175.7300629446527;3184.2039490127468;1269.2130878855075;1294.6375014995817;1318.3276528970198;1334.7063645997864;1342.4630147940804;10830.783384332348;10847.67456976292;10856.782864342747;10866.293690910066;10870.891291087068;4058.837646494107;4323.286657701444;4381.496127945264;4414.933775993362;4425.84509542171;9194.482910319855;9226.907730922605;9242.953785231031;9249.352545321763;9251.398659087272;4935.111679897581;4940.802367905316;4943.382077240345;4951.306817045616;4953.380827396575;4448.199324121689;4555.817767289272;4590.678652494294;4603.516630753789;4605.171069207508;3335.4830001349346;3457.827108644608;3497.527833742463;3546.3261980223674;3604.891853244407;1119.367557330106;1128.898399144081;1130.0846314485293;1134.5178299222798;1136.5681041204455;870.6647940542937;943.9622415103319;963.6627180486773;969.6606187834343;989.8317286061387;5381.515663590218;5518.503334699896;5609.719514024282;5723.484886360247;5744.971275143597;921.8386782313203;952.1145334838528;964.5032808103832;968.6900191938653;975.0882924465648;3635.1457407509215;3687.557493100845;3818.885433437001;3867.3812190049457;3980.601939805996;9455;9664.130228279348;9782.266330025277;9822.361546499455;9823.36148150368;8834.249088827393;9687.806243875082;9697.418154910727;9710.320277580642;9760.902390976142;7123.074000380021;7420.480566360411;7431.8142046449275;7439.665215065335;7443.813904652318;5303.2840566524565;5345.54562862161;5582.9720851397105;5643.830685079454;5710.543156547383;8520.659173632985;8790.077041910507;8805.911940880766;8927.151920567536;8972.955135224282;8908.064653211319;9000.189982901571;9060.95469522648;9099.272058235192;9109.407888487227;7227.927720722725;7379.409647228316;7384.630768461663;7390.408767298711;7393.667284972296;7716;7760;7832.843343132991;7844.176361481962;7861.882071768814;7071.186813516511;7104.822379440556;7178.246284140299;7272.8909066362985;7315.085614298213;7061.611611361424;7064.222935476991;7070.611116388648;7075.363217310467;7151.642417878981;6345.016522439029;6402.743890244338;6437.066625339393;6452.12896258999;6470.06184103293;4799.5680388763785;4850;4864.221724524059;4891.73095479752;4972.801087956441;4952;4959.355283813081;5014.874628134326;5018.573421259088;5104.846854594368 09:38:21 INFO - PROCESS | 8129 | |4;prototype.html;370.1782043862625;384.38498402555916;392.54268776874926;401.06151605243724;404.13313442665464;5013.498650134985;5083.237514372846;5090.185570308751;5154.329937108176;5192;5015.924761128586;5150.74246287685;5164.6384753067305;5165;5183.196604526295;3956.1296514766705;4039.4950631171105;4074.5517993020744;4184.100418410048;4185.246655601991;4150.709450338478;4153.937690934638;4165.375193720935;4167.874673838062;4176.327150623603;4138.482689663792;4139.296319625656;4145.626893579577;4145.917081658365;4147.336426171798;3839;4147.813348399314;4149.232392007473;4155.646770024551;4156.667466602665;3570.80360580168;3597.712183025352;3603.1712706077487;3614.493970844084;3625.5286812714444;4926.8768280793265;5008.323876276692;5016.623753218523;5021.497850214986;5021.639829280232;4624.537546245382;4625.964575586765;4638.67529272951;4664.836730714409;4667.88330291742;5205.947940520584;5334.813281535127;5344.465553444663;5360.356757189163;5360.812371567015;3938.7045971552247;3939.409088636699;3941.369380899071;3942.1668923950215;3953.090789118519;5584.636998595119;5609.467100625434;5633.661980281156;5635.774569017234;5643.266375371176;3945.2306800174265;4024.4768180136393;4041.6766658667234;4053.250148722496;4056.8985775355854;4097.672186225154;4107.301758701028;4117.21772863155;4123.154753275631;4124.793760311973;4660.953390466119;4662.160811053975;4668.949486365541;4690.835820746258;4691.835785747482;5006.974965125224;5217.34783152106;5234.738263086831;5360.544353729899;5463.48096930791;1333.646583655332;1346.9865301347056;1348.2921466230307;1360.7482615715926;1365.2491129878526;911.1571796088591;963.7735132243918;969.3795970578835;975.5902520941222;979.8138353712772;5065.797368105234;5070.721110338966;5085.211792172219;5089.592832573385;5126.179811230185;5348;5348.689571055116;5351.732413379315;5357.642637239602;5362.865928351823;4971.37857767779;5077.740405519508;5090.872728181751;5093.006863661624;5288.68267903927;2839.6714693951176;2877.1512403840916;2878.3955369372247;2878.510088773196;2909.7526710229868;5591.043134902751;5618.97190514053;5646.915296270559;5691.690911090473;5696.51579615729;5396.622236443412;5587;5635.520980716685;5772.278465191851;5773.6431938494425;5846.56150788684;5887.322957859816;5899.587028907935;5900.468957793733;5914.970425147847;5472.835814925558;5608.971955140199;5618.606697531133;5627.465390787868;5635.718214089361;5495.752691128989;5504.073574248981;5510.476504732044;5513.365962914316;5542.667439953616;5796.246782778147;5804.5356371491025;5825.446582574648;5851.64890106595;5855.59010869235;3933.8033098344395;3964;3984.7011474140136;4001.8599349022643;4048.4534587830267;5386.703731294817;5489.917651235155;5576.637518561281;5606.467385598362;5624.184493248538;5349.304590403222;5357.19642053695;5360.946390536044;5374.811881584282;5383.300170977748;5468.535174510212;5472.699001554953;5473.562115030708;5540.279763630702;5548.389677135593;5334.253204551288;5472.781088756406;5481.643693159932;5491.736900512984;5503.3395992481155;5456.372517160577;5492.780288788404;5530.649473973625;5531.585131115102;5544.029794785977;5276.788928442819;5428.945710543002;5432.076546986943;5436.809711660231;5449.482299181572 09:38:21 INFO - PROCESS | 8129 | |5;yui.html;741.462439731195;745.5936514599542;778.5095389904363;809.8906647602573;863.3740538109871;13540.593782186537;14337.71324573508;14350.354234059467;14388.20864852434;14442.133471991674;13546;13708.725825483509;13724.45102195911;13730.70463088859;13735.381907814148;821.7904434369242;843.6878355008655;846.9280111190557;851.1190917400492;852.5055467828645;817.448222449844;817.8936738224022;819.3035919468434;819.8647223208163;820.4502983001404;880.2781718990431;881.7046289493028;882.3206131278912;882.571952602987;882.5807741322884;877.2937785083005;878.2052242720348;878.4509681449094;878.8066625342415;879.1955360844819;639.098870592467;640.0271587187449;640.3340525853107;641.1312671330327;641.3298103481873;15027;15123.319450624638;15136.545903622799;15145.848541514664;15182.316795744218;13509;13926.164430134224;14075.788863167061;14093.647658808612;14320.42718291267;11643.126765492538;11750.471228794724;11755.471003804763;11811.704707382385;11847.407629618485;3742.3076730804614;3758.30471362796;3796.791176485292;3800.1259710266795;3911.765294082364;50842;51813.22280165838;52676.47323526792;60016.69991650058;77464;11508.36703981289;11710.76578468426;11743.47154378055;11751.764964700658;11857.644270671894;21152.365429037152;21175.78824211769;21201.15195392167;21339.78660213378;21384.144634214455;7932.405069619802;7977.641006154737;8021.5187088774865;8052.315553177928;8327.292180164633;4385.495667998157;4401.119776044804;4413.58070983256;4423;4453.4433195850515;3330.5670262866156;3511.3152935177905;3513.0690367052753;3550.485179648988;3556.9110772230383;655.8425977765397;666.6699983508098;670.2024590737008;673.0644404278057;679.9694013769392;623.1556241293073;634.1375729008545;634.3941535833268;637.3021541412146;638.0716058135401;8746;9395.62417503306;9774.218062554979;9778.336583868799;10146.847797283046;738.1216352540459;756.410000199834;763.1262204775612;766.0888623231697;769.9076110866732;4636.675432719745;4643.396358473445;4680.976595117003;4707.646926480527;4728.172569800271;77506.22493775103;77876.22123778804;81816.59091704503;82476.58761706273;82525.17474825175;62816;63056.80261565967;69614.65192674106;70768;73710;63037.36962630315;65546;66020;66286.66856665783;67021.6648916762;8813.647454101763;9034.86447703272;9035.096490351176;9035.322350823582;9081.182693557612;43636;44464.777676111415;45610.54389456063;45621;45949.310760337954;53024.46975530195;53225;53985.73007134939;54546;54929.45070549403;11237.494312755782;11386.202965792316;11505.884941150482;11537.942310288396;11580.942095289469;12847.807282890577;12898.613041608767;13095.279759613313;13233.80149297742;13322.082692212163;11976.58081967127;12038.699032524257;12264.38678066111;12874.549390771279;13299.335033248492;12752.6811829705;12882.420291087112;13665.248411337472;13753.243571603258;13769.173849569059;12670.936645316715;12916.547920822726;13286.468541258242;13841.792373114613;13851.515196968461;10577.865813297345;11029.83455248156;11256;11291.661250162508;11418.086553075567;9958.302918795904;10574.524146413276;10730.678079657622;10755.892441075803;10760.946195268974 09:38:21 INFO - PROCESS | 8129 | __end_tp_report 09:38:21 INFO - PROCESS | 8129 | __start_cc_report 09:38:21 INFO - PROCESS | 8129 | _x_x_mozilla_cycle_collect,2153 09:38:21 INFO - PROCESS | 8129 | __end_cc_report 09:38:21 INFO - PROCESS | 8129 | __startTimestamp1482946701110__endTimestamp 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | ------- Summary: start ------- 09:38:21 INFO - PROCESS | 8129 | Number of tests: 6 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#0] dojo.html Cycles:165 Average:5327.51 Median:5559.42 stddev:633.07 (11.4%) stddev-sans-first:610.82 09:38:21 INFO - PROCESS | 8129 | Values: 3118.3 3148.8 3154.3 3182.0 3203.1 5258.2 5307.2 5312.7 5385.6 5421.2 5382.2 5393.1 5408.1 5408.2 5423.2 4802.0 4804.6 4818.0 4854.3 4900.3 4767.9 4790.7 4801.4 4806.0 4811.0 4809.4 4811.1 4811.1 4814.0 4817.1 4799.0 4812.4 4812.9 4816.0 4816.6 4792.5 4798.6 4802.5 4805.0 4819.3 5298.8 5370.0 5441.8 5506.3 5510.4 5591.7 5807.4 5810.0 5813.1 5859.1 5688.0 5727.0 5733.8 5773.0 5786.2 5380.2 5396.9 5400.4 5404.6 5406.6 5763.5 5882.9 5884.7 5893.5 5894.7 5587.8 5636.4 5675.2 5681.3 5745.0 5815.3 5821.0 5823.1 5823.4 5825.4 5761.4 5763.5 5777.0 5780.9 5783.5 5517.1 5559.4 5647.7 5655.3 5655.9 4261.6 4274.2 4279.1 4279.4 4312.5 3793.4 3798.4 3800.1 3801.8 3840.2 5351.7 5401.5 5422.4 5440.5 5464.2 5811.6 5820.1 5822.5 5835.1 5872.1 5481.5 5491.5 5507.8 5508.0 5510.4 4676.1 4687.5 4730.9 4769.8 4774.7 5884.1 5887.2 5890.5 5944.1 5951.6 5624.1 5626.2 5629.5 5633.4 5636.2 5762.2 5786.8 5889.5 5889.8 6003.9 5719.4 5783.0 5813.7 5816.2 5819.3 5857.8 5859.6 5861.9 5864.6 5865.1 5648.0 5707.3 5712.1 5713.2 5715.1 5534.0 5575.4 5577.7 5660.2 5663.2 5573.1 5620.7 5620.9 5621.6 5637.4 5681.4 5685.1 5691.9 5692.7 5693.8 5542.6 5545.3 5547.1 5548.8 5550.5 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#1] ext.html Cycles:180 Average:57583.65 Median:38570.21 stddev:113366.01 (293.9%) stddev-sans-first:113603.22 09:38:21 INFO - PROCESS | 8129 | Values: 572.4 575.2 585.2 602.4 608.6 38836.2 41589.0 41692.8 45973.3 46026.8 43314.6 43775.6 43804.8 43810.0 43822.8 8199.5 8405.0 8622.3 8799.1 8800.5 10103.6 10396.2 10418.8 10728.9 10731.6 10602.9 10684.2 10686.9 10692.7 10694.9 10534.2 10601.0 10611.5 10612.2 10612.2 10421.7 10475.9 10495.5 10500.3 10501.9 40738.0 40897.4 41038.6 41053.6 41061.6 4086.9 4157.4 4170.9 4244.4 4267.7 31094.2 31170.7 31566.4 31855.2 32453.0 3639.5 3829.1 3880.6 3881.2 3911.3 333516.0 336580.0 338910.0 339390.0 339708.0 50533.5 51006.2 51082.5 51085.5 51106.2 52869.0 53131.2 53233.5 53241.0 53254.5 14777.2 15181.3 15191.6 15201.7 15204.4 10343.7 10634.2 10885.8 11934.9 11976.7 15926.4 16269.3 16272.8 16290.3 16296.6 6530.2 6603.6 6678.8 6679.0 6709.7 891.9 898.7 911.9 917.5 943.1 29854.0 30640.0 30662.0 31088.5 31132.5 1051.2 1063.7 1080.0 1083.6 1093.6 15582.3 16144.8 16278.0 16331.9 16633.2 497710.0 504364.0 507128.0 508618.0 509613.0 56527.4 57440.7 57722.0 57764.4 57809.1 1348.8 1387.8 1396.5 1397.1 1398.2 28298.7 29414.0 29840.6 29976.5 30414.2 47759.8 48537.0 48550.5 48635.8 48637.3 422220.0 424650.0 424743.0 424769.0 424995.0 23555.4 24307.0 25443.5 26549.2 26888.7 45418.1 45854.3 45894.5 45972.3 46053.0 38155.2 38777.6 39070.4 40054.4 40416.2 38435.6 38685.8 38756.8 38799.6 39150.6 37967.2 38454.6 38897.6 39142.4 39152.2 31772.0 33911.2 34384.3 34555.7 34859.0 26109.2 26142.7 26184.0 26491.7 26689.5 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#2] jquery.html Cycles:180 Average:24369.10 Median:401.31 stddev:62421.54 (15554.5%) stddev-sans-first:62570.69 09:38:21 INFO - PROCESS | 8129 | Values: 393.7 399.9 402.7 406.5 426.7 17299.9 17701.6 18618.8 18757.9 18761.2 17991.2 18077.6 18131.3 18202.0 18281.1 5521.9 5559.1 5646.2 5676.5 5737.6 5638.4 5650.2 5683.5 5687.8 5689.7 5442.5 5578.4 5613.9 5693.5 5696.9 5591.4 5609.3 5628.3 5638.5 5648.6 5070.9 5115.6 5326.5 5365.4 5419.8 11904.3 11919.6 11971.2 12113.0 12123.1 2798.4 2987.9 3015.0 3044.4 3085.5 9865.7 9913.2 9944.5 10214.4 10528.7 1872.5 1924.9 1929.8 1944.3 1973.6 73446.6 75065.6 75093.0 75533.6 76139.9 9048.0 9530.3 9689.5 10770.9 10933.8 12938.0 12977.5 13025.9 13034.4 13054.9 7321.6 7326.1 7505.5 7652.3 7670.7 7318.6 7359.9 7532.7 7538.8 7545.9 4025.7 4072.8 4097.0 4168.8 4169.7 1988.7 2031.3 2043.7 2060.9 2068.7 860.0 868.0 869.4 887.8 913.3 16823.5 16912.0 17458.7 17652.2 17704.7 858.6 860.7 866.2 870.3 887.4 9035.6 9664.4 9982.3 10037.5 10190.2 348366.0 350353.0 351833.0 412286.0 413161.0 73888.6 75046.0 75937.0 80286.6 88095.6 1022.7 1023.0 1067.6 1069.2 1070.1 15974.0 16404.8 16540.5 16803.7 16982.3 38100.8 38710.0 38825.4 38908.8 39065.6 50768.0 53100.2 53141.7 55029.9 55357.7 8359.5 8596.7 8641.8 8659.7 8660.1 12460.5 12534.3 12596.7 12641.2 12786.6 10312.9 11182.5 11209.7 11658.8 11891.3 10289.3 10466.8 10624.5 10701.7 11195.2 11052.5 11082.2 11107.5 11167.3 11346.8 8347.0 8494.8 8511.0 8618.7 8811.6 11005.4 11176.1 11249.2 11370.4 11568.2 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#3] mootools.html Cycles:180 Average:5288.49 Median:5324.41 stddev:2908.03 (54.6%) stddev-sans-first:2900.63 09:38:21 INFO - PROCESS | 8129 | Values: 1286.4 1354.9 1358.2 1363.5 1367.4 6789.6 7495.9 7574.2 7680.7 8111.0 7960.8 8019.3 8039.7 8045.6 8045.8 1925.4 2211.0 2246.4 2248.0 2248.6 2015.0 2028.9 2031.7 2124.2 2201.4 2203.0 2204.5 2207.8 2208.5 2209.0 1934.7 1946.0 1950.2 1954.3 1957.2 2118.2 2175.9 2190.7 2193.5 2195.0 7946.8 7990.9 8019.8 8020.8 8025.2 4782.8 4913.5 4930.3 4936.4 4938.7 3087.4 3110.1 3167.2 3175.7 3184.2 1269.2 1294.6 1318.3 1334.7 1342.5 10830.8 10847.7 10856.8 10866.3 10870.9 4058.8 4323.3 4381.5 4414.9 4425.8 9194.5 9226.9 9243.0 9249.4 9251.4 4935.1 4940.8 4943.4 4951.3 4953.4 4448.2 4555.8 4590.7 4603.5 4605.2 3335.5 3457.8 3497.5 3546.3 3604.9 1119.4 1128.9 1130.1 1134.5 1136.6 870.7 944.0 963.7 969.7 989.8 5381.5 5518.5 5609.7 5723.5 5745.0 921.8 952.1 964.5 968.7 975.1 3635.1 3687.6 3818.9 3867.4 3980.6 9455.0 9664.1 9782.3 9822.4 9823.4 8834.2 9687.8 9697.4 9710.3 9760.9 7123.1 7420.5 7431.8 7439.7 7443.8 5303.3 5345.5 5583.0 5643.8 5710.5 8520.7 8790.1 8805.9 8927.2 8973.0 8908.1 9000.2 9061.0 9099.3 9109.4 7227.9 7379.4 7384.6 7390.4 7393.7 7716.0 7760.0 7832.8 7844.2 7861.9 7071.2 7104.8 7178.2 7272.9 7315.1 7061.6 7064.2 7070.6 7075.4 7151.6 6345.0 6402.7 6437.1 6452.1 6470.1 4799.6 4850.0 4864.2 4891.7 4972.8 4952.0 4959.4 5014.9 5018.6 5104.8 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#4] prototype.html Cycles:180 Average:4581.04 Median:5159.48 stddev:1329.76 (25.8%) stddev-sans-first:1295.39 09:38:21 INFO - PROCESS | 8129 | Values: 370.2 384.4 392.5 401.1 404.1 5013.5 5083.2 5090.2 5154.3 5192.0 5015.9 5150.7 5164.6 5165.0 5183.2 3956.1 4039.5 4074.6 4184.1 4185.2 4150.7 4153.9 4165.4 4167.9 4176.3 4138.5 4139.3 4145.6 4145.9 4147.3 3839.0 4147.8 4149.2 4155.6 4156.7 3570.8 3597.7 3603.2 3614.5 3625.5 4926.9 5008.3 5016.6 5021.5 5021.6 4624.5 4626.0 4638.7 4664.8 4667.9 5205.9 5334.8 5344.5 5360.4 5360.8 3938.7 3939.4 3941.4 3942.2 3953.1 5584.6 5609.5 5633.7 5635.8 5643.3 3945.2 4024.5 4041.7 4053.3 4056.9 4097.7 4107.3 4117.2 4123.2 4124.8 4661.0 4662.2 4668.9 4690.8 4691.8 5007.0 5217.3 5234.7 5360.5 5463.5 1333.6 1347.0 1348.3 1360.7 1365.2 911.2 963.8 969.4 975.6 979.8 5065.8 5070.7 5085.2 5089.6 5126.2 5348.0 5348.7 5351.7 5357.6 5362.9 4971.4 5077.7 5090.9 5093.0 5288.7 2839.7 2877.2 2878.4 2878.5 2909.8 5591.0 5619.0 5646.9 5691.7 5696.5 5396.6 5587.0 5635.5 5772.3 5773.6 5846.6 5887.3 5899.6 5900.5 5915.0 5472.8 5609.0 5618.6 5627.5 5635.7 5495.8 5504.1 5510.5 5513.4 5542.7 5796.2 5804.5 5825.4 5851.6 5855.6 3933.8 3964.0 3984.7 4001.9 4048.5 5386.7 5489.9 5576.6 5606.5 5624.2 5349.3 5357.2 5360.9 5374.8 5383.3 5468.5 5472.7 5473.6 5540.3 5548.4 5334.3 5472.8 5481.6 5491.7 5503.3 5456.4 5492.8 5530.6 5531.6 5544.0 5276.8 5428.9 5432.1 5436.8 5449.5 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - PROCESS | 8129 | [#5] yui.html Cycles:180 Average:16905.89 Median:4640.04 stddev:21471.12 (462.7%) stddev-sans-first:21497.04 09:38:21 INFO - PROCESS | 8129 | Values: 741.5 745.6 778.5 809.9 863.4 13540.6 14337.7 14350.4 14388.2 14442.1 13546.0 13708.7 13724.5 13730.7 13735.4 821.8 843.7 846.9 851.1 852.5 817.4 817.9 819.3 819.9 820.5 880.3 881.7 882.3 882.6 882.6 877.3 878.2 878.5 878.8 879.2 639.1 640.0 640.3 641.1 641.3 15027.0 15123.3 15136.5 15145.8 15182.3 13509.0 13926.2 14075.8 14093.6 14320.4 11643.1 11750.5 11755.5 11811.7 11847.4 3742.3 3758.3 3796.8 3800.1 3911.8 50842.0 51813.2 52676.5 60016.7 77464.0 11508.4 11710.8 11743.5 11751.8 11857.6 21152.4 21175.8 21201.2 21339.8 21384.1 7932.4 7977.6 8021.5 8052.3 8327.3 4385.5 4401.1 4413.6 4423.0 4453.4 3330.6 3511.3 3513.1 3550.5 3556.9 655.8 666.7 670.2 673.1 680.0 623.2 634.1 634.4 637.3 638.1 8746.0 9395.6 9774.2 9778.3 10146.8 738.1 756.4 763.1 766.1 769.9 4636.7 4643.4 4681.0 4707.6 4728.2 77506.2 77876.2 81816.6 82476.6 82525.2 62816.0 63056.8 69614.7 70768.0 73710.0 63037.4 65546.0 66020.0 66286.7 67021.7 8813.6 9034.9 9035.1 9035.3 9081.2 43636.0 44464.8 45610.5 45621.0 45949.3 53024.5 53225.0 53985.7 54546.0 54929.5 11237.5 11386.2 11505.9 11537.9 11580.9 12847.8 12898.6 13095.3 13233.8 13322.1 11976.6 12038.7 12264.4 12874.5 13299.3 12752.7 12882.4 13665.2 13753.2 13769.2 12670.9 12916.5 13286.5 13841.8 13851.5 10577.9 11029.8 11256.0 11291.7 11418.1 9958.3 10574.5 10730.7 10755.9 10760.9 09:38:21 INFO - PROCESS | 8129 | -------- Summary: end -------- 09:38:21 INFO - PROCESS | 8129 | 09:38:21 INFO - TEST-INFO | 8129: exit 0 09:38:21 INFO - TEST-OK | dromaeo_css | took 1116497ms 09:38:21 INFO - TEST-START | kraken 09:38:21 INFO - operating with platform_type : linux_ 09:38:21 INFO - Initialising browser for kraken test... 09:38:21 INFO - TEST-INFO | started process 8431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNRIxI/profile http://localhost:58473/getInfo.html) 09:38:22 INFO - PROCESS | 8431 | 1482946702456 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:38:25 INFO - PROCESS | 8431 | __metrics Screen width/height:1600/1200 09:38:25 INFO - PROCESS | 8431 | colorDepth:24 09:38:25 INFO - PROCESS | 8431 | Browser inner width/height: 1024/697 09:38:25 INFO - PROCESS | 8431 | __metrics 09:38:26 INFO - PROCESS | 8431 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:38:26 INFO - PROCESS | 8431 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:27 INFO - PROCESS | 8431 | console.error: PushService: 09:38:27 INFO - PROCESS | 8431 | stateChangeProcessEnqueue: Error transitioning state 09:38:27 INFO - PROCESS | 8431 | UnknownError 09:38:27 INFO - TEST-INFO | 8431: exit 0 09:38:27 INFO - Browser initialized. 09:38:27 INFO - Running cycle 1/1 for kraken test... 09:38:27 INFO - TEST-INFO | started process 8514 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNRIxI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:39 INFO - PROCESS | 8514 | RSS: Main: 146817024 09:38:39 INFO - PROCESS | 8514 | 09:38:42 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58473/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:42 INFO - PROCESS | 8514 | RSS: Main: 201736192 09:38:42 INFO - PROCESS | 8514 | 09:38:45 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58473/tests/kraken/driver.html?testName=audio-dft) 09:38:46 INFO - PROCESS | 8514 | RSS: Main: 181006336 09:38:46 INFO - PROCESS | 8514 | 09:38:49 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58473/tests/kraken/driver.html?testName=audio-fft) 09:38:49 INFO - PROCESS | 8514 | RSS: Main: 263249920 09:38:49 INFO - PROCESS | 8514 | 09:38:51 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58473/tests/kraken/driver.html?testName=audio-oscillator) 09:38:52 INFO - PROCESS | 8514 | RSS: Main: 175190016 09:38:52 INFO - PROCESS | 8514 | 09:38:54 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58473/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:55 INFO - PROCESS | 8514 | RSS: Main: 178421760 09:38:55 INFO - PROCESS | 8514 | 09:38:58 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58473/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:58 INFO - PROCESS | 8514 | RSS: Main: 217542656 09:38:58 INFO - PROCESS | 8514 | 09:39:02 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58473/tests/kraken/driver.html?testName=imaging-desaturate) 09:39:02 INFO - PROCESS | 8514 | RSS: Main: 216887296 09:39:02 INFO - PROCESS | 8514 | 09:39:05 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58473/tests/kraken/driver.html?testName=json-parse-financial) 09:39:06 INFO - PROCESS | 8514 | RSS: Main: 215298048 09:39:06 INFO - PROCESS | 8514 | 09:39:07 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58473/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:08 INFO - PROCESS | 8514 | RSS: Main: 177881088 09:39:08 INFO - PROCESS | 8514 | 09:39:10 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:39:10 INFO - PROCESS | 8514 | RSS: Main: 185044992 09:39:10 INFO - PROCESS | 8514 | 09:39:12 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:39:13 INFO - PROCESS | 8514 | RSS: Main: 193650688 09:39:13 INFO - PROCESS | 8514 | 09:39:15 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:39:16 INFO - PROCESS | 8514 | RSS: Main: 203079680 09:39:16 INFO - PROCESS | 8514 | 09:39:19 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:39:19 INFO - PROCESS | 8514 | RSS: Main: 199458816 09:39:19 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | Cycle 1(1): loaded http://localhost:58473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58473/tests/kraken/driver.html?testName=ai-astar) 09:39:21 INFO - PROCESS | 8514 | RSS: Main: 251682816 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | __start_tp_report 09:39:21 INFO - PROCESS | 8514 | _x_x_mozilla_page_load 09:39:21 INFO - PROCESS | 8514 | _x_x_mozilla_page_load_details 09:39:21 INFO - PROCESS | 8514 | |i|pagename|runs| 09:39:21 INFO - PROCESS | 8514 | |0;ai-astar;110;111;110;111;110;111;110;109;111;109 09:39:21 INFO - PROCESS | 8514 | |1;audio-beat-detection;150;118;121;159;121;124;118;120;145;120 09:39:21 INFO - PROCESS | 8514 | |2;audio-dft;161;160;161;160;159;159;160;162;161;160 09:39:21 INFO - PROCESS | 8514 | |3;audio-fft;77;75;76;110;76;77;76;77;102;75 09:39:21 INFO - PROCESS | 8514 | |4;audio-oscillator;83;82;103;82;82;83;108;83;83;111 09:39:21 INFO - PROCESS | 8514 | |5;imaging-gaussian-blur;117;116;118;117;116;116;118;116;116;117 09:39:21 INFO - PROCESS | 8514 | |6;imaging-darkroom;146;147;147;146;146;146;146;146;146;146 09:39:21 INFO - PROCESS | 8514 | |7;imaging-desaturate;97;96;96;96;96;96;96;96;96;95 09:39:21 INFO - PROCESS | 8514 | |8;json-parse-financial;62;50;50;51;51;53;51;52;54;52 09:39:21 INFO - PROCESS | 8514 | |9;json-stringify-tinderbox;67;68;68;67;68;67;67;67;68;68 09:39:21 INFO - PROCESS | 8514 | |10;stanford-crypto-aes;82;83;80;80;80;80;85;79;79;89 09:39:21 INFO - PROCESS | 8514 | |11;stanford-crypto-ccm;137;125;124;129;132;123;123;121;175;126 09:39:21 INFO - PROCESS | 8514 | |12;stanford-crypto-pbkdf2;164;167;164;171;171;169;167;164;167;163 09:39:21 INFO - PROCESS | 8514 | |13;stanford-crypto-sha256-iterative;66;64;64;62;61;61;67;61;63;60 09:39:21 INFO - PROCESS | 8514 | __end_tp_report 09:39:21 INFO - PROCESS | 8514 | __start_cc_report 09:39:21 INFO - PROCESS | 8514 | _x_x_mozilla_cycle_collect,1630 09:39:21 INFO - PROCESS | 8514 | __end_cc_report 09:39:21 INFO - PROCESS | 8514 | __startTimestamp1482946761906__endTimestamp 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | ------- Summary: start ------- 09:39:21 INFO - PROCESS | 8514 | Number of tests: 14 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 09:39:21 INFO - PROCESS | 8514 | Values: 110.0 111.0 110.0 111.0 110.0 111.0 110.0 109.0 111.0 109.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:122.50 stddev:15.46 (12.6%) stddev-sans-first:14.53 09:39:21 INFO - PROCESS | 8514 | Values: 150.0 118.0 121.0 159.0 121.0 124.0 118.0 120.0 145.0 120.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 09:39:21 INFO - PROCESS | 8514 | Values: 161.0 160.0 161.0 160.0 159.0 159.0 160.0 162.0 161.0 160.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.00 stddev:12.76 (16.8%) stddev-sans-first:13.40 09:39:21 INFO - PROCESS | 8514 | Values: 77.0 75.0 76.0 110.0 76.0 77.0 76.0 77.0 102.0 75.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:82.50 stddev:12.12 (14.7%) stddev-sans-first:12.59 09:39:21 INFO - PROCESS | 8514 | Values: 83.0 82.0 103.0 82.0 82.0 83.0 108.0 83.0 83.0 111.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 09:39:21 INFO - PROCESS | 8514 | Values: 117.0 116.0 118.0 117.0 116.0 116.0 118.0 116.0 116.0 117.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:39:21 INFO - PROCESS | 8514 | Values: 146.0 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 09:39:21 INFO - PROCESS | 8514 | Values: 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:3.53 (6.8%) stddev-sans-first:1.33 09:39:21 INFO - PROCESS | 8514 | Values: 62.0 50.0 50.0 51.0 51.0 53.0 51.0 52.0 54.0 52.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:68.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 09:39:21 INFO - PROCESS | 8514 | Values: 67.0 68.0 68.0 67.0 68.0 67.0 67.0 67.0 68.0 68.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:81.00 stddev:3.20 (3.9%) stddev-sans-first:3.39 09:39:21 INFO - PROCESS | 8514 | Values: 82.0 83.0 80.0 80.0 80.0 80.0 85.0 79.0 79.0 89.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:127.50 stddev:16.03 (12.6%) stddev-sans-first:16.88 09:39:21 INFO - PROCESS | 8514 | Values: 137.0 125.0 124.0 129.0 132.0 123.0 123.0 121.0 175.0 126.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:167.00 stddev:2.95 (1.8%) stddev-sans-first:2.96 09:39:21 INFO - PROCESS | 8514 | Values: 164.0 167.0 164.0 171.0 171.0 169.0 167.0 164.0 167.0 163.0 09:39:21 INFO - PROCESS | 8514 | 09:39:21 INFO - PROCESS | 8514 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.50 stddev:2.33 (3.7%) stddev-sans-first:2.19 09:39:21 INFO - PROCESS | 8514 | Values: 66.0 64.0 64.0 62.0 61.0 61.0 67.0 61.0 63.0 60.0 09:39:21 INFO - PROCESS | 8514 | -------- Summary: end -------- 09:39:21 INFO - PROCESS | 8514 | 09:39:22 INFO - TEST-INFO | 8514: exit 0 09:39:22 INFO - TEST-OK | kraken | took 61018ms 09:39:22 INFO - SUITE-END | took 1177s 09:39:23 INFO - Completed test suite (00:19:37) 09:39:23 INFO - javascript benchmark 09:39:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.2827949571597, 3148.8425578721058, 3154.258749193939, 3181.968180318196, 3203.135153508553, 5258.158694608864, 5307.150855863063, 5312.734363281835, 5385.649932754368, 5421.241026256323, 5382.2464854920245, 5393.056215162341, 5408.134698448249, 5408.242846001563, 5423.159410291411, 4801.951980480203, 4804.615630749549, 4818.036392721469, 4854.34466347044, 4900.264960255972, 4767.880802979936, 4790.736509491977, 4801.423829140508, 4805.951940480585, 4810.951890481086, 4809.374781278429, 4811.061842940628, 4811.061842940628, 4813.951860481403, 4817.132916075105, 4798.952010479903, 4812.37439132914, 4812.879678008043, 4815.975920120412, 4816.579109162771, 4792.472827988919, 4798.568128868384, 4802.471728109905, 4805.014971930746, 4819.253015782524, 5298.841034768963, 5370.0, 5441.809536666237, 5506.311711036121, 5510.448955104497, 5591.7483713233005, 5807.419258074201, 5810.041343178371, 5813.098969659667, 5859.091840764689, 5688.033034384164, 5726.971365143189, 5733.82798516041, 5772.9711351442975, 5786.161006654053, 5380.219868119101, 5396.946030539723, 5400.432954539756, 5404.567634589223, 5406.594505412031, 5763.538916886639, 5882.941170588239, 5884.705764711747, 5893.469587737039, 5894.6757928314355, 5587.776488940478, 5636.351819540805, 5675.2054712340305, 5681.289838770153, 5744.971275143597, 5815.3021637402935, 5820.970895145497, 5823.068309070529, 5823.359430462646, 5825.38833422493, 5761.39505351932, 5763.510101641324, 5777.0, 5780.942190578125, 5783.537317014629, 5517.062099443105, 5559.416261292506, 5647.689377084218, 5655.349634792052, 5655.943440565624, 4261.637760790368, 4274.187904298174, 4279.101388708344, 4279.40088387626, 4312.504062032906, 3793.393057110849, 3798.430235464647, 3800.1259710266795, 3801.752886062397, 3840.1935593525113, 5351.678899266057, 5401.4868587484125, 5422.376426710979, 5440.483154100433, 5464.153056276284, 5811.593188476766, 5820.126980952891, 5822.534197264209, 5835.066389377764, 5872.060470324727, 5481.479259470344, 5491.478309560585, 5507.779688812483, 5507.972460137754, 5510.421405752419, 4676.111538807616, 4687.507811679793, 4730.929036064462, 4769.833055843098, 4774.665773395829, 5884.117382392504, 5887.20522729426, 5890.4698577128265, 5944.108383742473, 5951.613145145551, 5624.128260119688, 5626.212330273683, 5629.521490673339, 5633.436656334334, 5636.154576813511, 5762.1644861494015, 5786.768529258951, 5889.52883769306, 5889.793857214977, 6003.939960600513, 5719.399463056319, 5783.016887129114, 5813.738381772915, 5816.2438882946635, 5819.301683797802, 5857.7949771757785, 5859.589828711949, 5861.853453663693, 5864.648121112747, 5865.090910908816, 5647.971760141173, 5707.286589176353, 5712.11462223356, 5713.200151978644, 5715.085586306171, 5534.03154447962, 5575.414581468887, 5577.721113944205, 5660.20757093999, 5663.2071509989455, 5573.052581061311, 5620.747066382104, 5620.887582248414, 5621.606487545832, 5637.379888212211, 5681.431856814285, 5685.090385538294, 5691.8861622768145, 5692.686902220417, 5693.800716975052, 5542.584306177134, 5545.306836645419, 5547.057000310037, 5548.778048878161, 5550.500454959073], "value": 5283.270788723657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.4304317204382, 575.1975993489083, 585.2099665451643, 602.3795490644635, 608.6074481959139, 38836.223275534474, 41589.0, 41692.791536042314, 45973.31040034394, 46026.76986615062, 43314.566854331446, 43775.56224437755, 43804.78097609508, 43810.0, 43822.78088609553, 8199.467034642728, 8405.033421156584, 8622.310215182786, 8799.076097009818, 8800.471971681687, 10103.646372376968, 10396.220283478731, 10418.791624167512, 10728.946355268212, 10731.570737170503, 10602.946985265102, 10684.19868509861, 10686.946565267162, 10692.732681682943, 10694.893051069468, 10534.157267418626, 10601.04590586847, 10611.522481488351, 10612.204084693602, 10612.20408469368, 10421.68734937953, 10475.895241047569, 10495.475226238657, 10500.317479363817, 10501.894981050169, 40738.0, 40897.386539201936, 41038.589614103774, 41053.58946410527, 41061.589384106075, 4086.9386959195635, 4157.397177409259, 4170.874873753762, 4244.427002354704, 4267.679924005712, 31094.222644433845, 31170.688293117004, 31566.36867262665, 31855.203619909455, 32453.0, 3639.5450568678916, 3829.061879839457, 3880.611938806125, 3881.2237552489332, 3911.3155197840547, 333516.0, 336580.0, 338910.0, 339390.0, 339708.0, 50533.494665053615, 51006.234906476435, 51082.489175108516, 51085.48914510882, 51106.23340649893, 52869.0, 53131.20303195455, 53233.467665323624, 53241.0, 53254.46745532495, 14777.187254701095, 15181.316840742193, 15191.620209494851, 15201.695966080837, 15204.391824327124, 10343.741406464747, 10634.202434817265, 10885.83671244932, 11934.940325298317, 11976.700582485508, 15926.362945482282, 16269.267882945296, 16272.75590866138, 16290.266937987819, 16296.592585185228, 6530.183727034121, 6603.636799976048, 6678.799636010927, 6679.0, 6709.664516774142, 891.9063498332582, 898.7169041751828, 911.9361644684941, 917.5228880981851, 943.0899182289124, 29854.0, 30640.0, 30662.0, 31088.533671994937, 31132.53301200484, 1051.1905832508926, 1063.6596289187542, 1079.9622013229657, 1083.6099004358425, 1093.6445655162104, 15582.298796554182, 16144.838551614333, 16278.0, 16331.918340408221, 16633.168341582874, 497710.0, 504364.0, 507128.0, 508618.0, 509613.0, 56527.43472565304, 57440.71279643575, 57722.0, 57764.4223557759, 57809.132863007086, 1348.7504811609883, 1387.8473367929519, 1396.4623619906206, 1397.0709478196886, 1398.216998480854, 28298.71701283043, 29414.0, 29840.552391714577, 29976.452001579237, 30414.239644009078, 47759.761201193774, 48537.0, 48550.5144948546, 48635.75682121567, 48637.27044094271, 422220.0, 424650.0, 424743.0, 424769.0, 424995.0, 23555.41111472227, 24307.0, 25443.491130177663, 26549.203523894314, 26888.731112688623, 45418.09163816771, 45854.31218531658, 45894.541054590365, 45972.31041534313, 46053.0, 38155.2368952625, 38777.6122238774, 39070.4139437903, 40054.39918401168, 40416.19167616573, 38435.6156438432, 38685.80657096697, 38756.80621596874, 38799.6120038796, 39150.6084939147, 37967.240655186186, 38454.61545384622, 38897.611023889396, 39142.412863806494, 39152.21695566016, 31772.0, 33911.152221194665, 34384.31231375408, 34555.65444345625, 34859.0, 26109.216723498324, 26142.73857261403, 26184.0, 26491.65151921519, 26689.466210676066], "value": 19536.316270675627, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.67718470853913, 399.8900302416835, 402.72614622057006, 406.4756464161232, 426.73968878983834, 17299.913500432496, 17701.557461063447, 18618.813811861877, 18757.906210468962, 18761.249550018, 17991.19039643216, 18077.63844723105, 18131.274749010056, 18202.0, 18281.085945702696, 5521.861953451165, 5559.054960656698, 5646.18130371096, 5676.489115979561, 5737.598368114233, 5638.351589567191, 5650.180723795047, 5683.488486036256, 5687.800926967557, 5689.687067211302, 5442.482964118402, 5578.44215578441, 5613.943860561403, 5693.544516438675, 5696.8860622787515, 5591.384947655775, 5609.298837645294, 5628.296462942132, 5638.492535671769, 5648.63283886546, 5070.873228169288, 5115.641905066647, 5326.547243484309, 5365.382980957162, 5419.810306639288, 11904.285742855456, 11919.582814601534, 11971.162018658697, 12113.0, 12123.090768192333, 2798.3563780330433, 2987.925301867449, 3014.9698503014906, 3044.3759029399143, 3085.460044492205, 9865.654702085392, 9913.206943444578, 9944.453055081967, 10214.438205898672, 10528.736781580521, 1872.513146581885, 1924.9133788979389, 1929.778075521305, 1944.2611807513197, 1973.5756812285283, 73446.63276683583, 75065.62467187684, 75093.0, 75533.62233188853, 76139.85790213206, 9048.0, 9530.332876698565, 9689.467079310565, 10770.946145269223, 10933.781324373627, 12938.0, 12977.545785897448, 13025.934870325587, 13034.41345139471, 13054.869451305365, 7321.560706357529, 7326.084239470067, 7505.549667019996, 7652.311291983749, 7670.654820533089, 7318.597477138808, 7359.889601655979, 7532.698692052366, 7538.773836784905, 7545.886811697829, 4025.7383270087353, 4072.775997320176, 4097.0, 4168.7707176105605, 4169.728967617095, 1988.6818109102473, 2031.349968010252, 2043.7138800567932, 2060.939504835348, 2068.7414073240843, 859.9551544872884, 867.9566021698889, 869.3523325122698, 887.7736177274753, 913.3058875254721, 16823.495295141165, 16912.0, 17458.738118928228, 17652.205650745746, 17704.734428983575, 858.553552152877, 860.6557377049105, 866.2290561400358, 870.3124531619949, 887.3522328700085, 9035.63857445708, 9664.371815831948, 9982.251331150193, 10037.498125093716, 10190.184785217165, 348366.0, 350353.0, 351833.0, 412286.0, 413161.0, 73888.63055684687, 75046.0, 75937.0, 80286.59856700679, 88095.55952220326, 1022.7187523431116, 1022.9744256393501, 1067.5622994572188, 1069.1553672598716, 1070.0850772589479, 15974.0, 16404.835951640332, 16540.503784886474, 16803.66392672164, 16982.320707171573, 38100.809495952344, 38710.0, 38825.41761873518, 38908.80545597254, 39065.609343906195, 50768.0, 53100.2034969468, 53141.7342913283, 55029.89940201254, 55357.723211385295, 8359.498430094214, 8596.656133754579, 8641.783955401166, 8659.653613855375, 8660.09069047741, 12460.501579936701, 12534.310612916377, 12596.685082873002, 12641.178323408949, 12786.61640150797, 10312.948435257777, 11182.496787644739, 11209.663710088711, 11658.76682466329, 11891.28652280866, 10289.2797504174, 10466.842997354894, 10624.512568240607, 10701.678949631523, 11195.216334856483, 11052.502637381176, 11082.16883733707, 11107.500162492546, 11167.329960202414, 11346.829797552879, 8346.958265208636, 8494.830103397773, 8511.0, 8618.655253789779, 8811.60347784364, 11005.449727513753, 11176.10591152722, 11249.212555121063, 11370.374629395463, 11568.24806387582], "value": 8598.802199615026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.421110500275, 1354.9119307245028, 1358.225811287566, 1363.4546181527392, 1367.357342049237, 6789.592624442531, 7495.887561686573, 7574.166841647421, 7680.654370553325, 8111.0, 7960.76117716468, 8019.318357939582, 8039.718609848656, 8045.637946292416, 8045.758627241177, 1925.3646296722118, 2210.999999999996, 2246.3822448826622, 2247.9775202248015, 2248.5615305015526, 2014.979850201494, 2028.8579799414047, 2031.7460317460318, 2124.2352753008904, 2201.3726088064905, 2203.030666506732, 2204.5039866030097, 2207.75714671386, 2208.4540821425066, 2208.9889550552225, 1934.7388102606108, 1946.0172612830502, 1950.1711772496774, 1954.3257576136211, 1957.2366776956999, 2118.195085867376, 2175.945601359963, 2190.69330293759, 2193.517426166241, 2194.9561008779897, 7946.801329966739, 7990.880136797953, 8019.799505012363, 8020.799480012988, 8025.23760242776, 4782.760861956888, 4913.459519452857, 4930.28510865926, 4936.358273424432, 4938.678985865908, 3087.4133914556164, 3110.0825256549147, 3167.192365946692, 3175.7300629446527, 3184.2039490127468, 1269.2130878855075, 1294.6375014995817, 1318.3276528970198, 1334.7063645997864, 1342.4630147940804, 10830.783384332348, 10847.67456976292, 10856.782864342747, 10866.293690910066, 10870.891291087068, 4058.837646494107, 4323.286657701444, 4381.496127945264, 4414.933775993362, 4425.84509542171, 9194.482910319855, 9226.907730922605, 9242.953785231031, 9249.352545321763, 9251.398659087272, 4935.111679897581, 4940.802367905316, 4943.382077240345, 4951.306817045616, 4953.380827396575, 4448.199324121689, 4555.817767289272, 4590.678652494294, 4603.516630753789, 4605.171069207508, 3335.4830001349346, 3457.827108644608, 3497.527833742463, 3546.3261980223674, 3604.891853244407, 1119.367557330106, 1128.898399144081, 1130.0846314485293, 1134.5178299222798, 1136.5681041204455, 870.6647940542937, 943.9622415103319, 963.6627180486773, 969.6606187834343, 989.8317286061387, 5381.515663590218, 5518.503334699896, 5609.719514024282, 5723.484886360247, 5744.971275143597, 921.8386782313203, 952.1145334838528, 964.5032808103832, 968.6900191938653, 975.0882924465648, 3635.1457407509215, 3687.557493100845, 3818.885433437001, 3867.3812190049457, 3980.601939805996, 9455.0, 9664.130228279348, 9782.266330025277, 9822.361546499455, 9823.36148150368, 8834.249088827393, 9687.806243875082, 9697.418154910727, 9710.320277580642, 9760.902390976142, 7123.074000380021, 7420.480566360411, 7431.8142046449275, 7439.665215065335, 7443.813904652318, 5303.2840566524565, 5345.54562862161, 5582.9720851397105, 5643.830685079454, 5710.543156547383, 8520.659173632985, 8790.077041910507, 8805.911940880766, 8927.151920567536, 8972.955135224282, 8908.064653211319, 9000.189982901571, 9060.95469522648, 9099.272058235192, 9109.407888487227, 7227.927720722725, 7379.409647228316, 7384.630768461663, 7390.408767298711, 7393.667284972296, 7716.0, 7760.0, 7832.843343132991, 7844.176361481962, 7861.882071768814, 7071.186813516511, 7104.822379440556, 7178.246284140299, 7272.8909066362985, 7315.085614298213, 7061.611611361424, 7064.222935476991, 7070.611116388648, 7075.363217310467, 7151.642417878981, 6345.016522439029, 6402.743890244338, 6437.066625339393, 6452.12896258999, 6470.06184103293, 4799.5680388763785, 4850.0, 4864.221724524059, 4891.73095479752, 4972.801087956441, 4952.0, 4959.355283813081, 5014.874628134326, 5018.573421259088, 5104.846854594368], "value": 4286.219229322439, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.1782043862625, 384.38498402555916, 392.54268776874926, 401.06151605243724, 404.13313442665464, 5013.498650134985, 5083.237514372846, 5090.185570308751, 5154.329937108176, 5192.0, 5015.924761128586, 5150.74246287685, 5164.6384753067305, 5165.0, 5183.196604526295, 3956.1296514766705, 4039.4950631171105, 4074.5517993020744, 4184.100418410048, 4185.246655601991, 4150.709450338478, 4153.937690934638, 4165.375193720935, 4167.874673838062, 4176.327150623603, 4138.482689663792, 4139.296319625656, 4145.626893579577, 4145.917081658365, 4147.336426171798, 3839.0, 4147.813348399314, 4149.232392007473, 4155.646770024551, 4156.667466602665, 3570.80360580168, 3597.712183025352, 3603.1712706077487, 3614.493970844084, 3625.5286812714444, 4926.8768280793265, 5008.323876276692, 5016.623753218523, 5021.497850214986, 5021.639829280232, 4624.537546245382, 4625.964575586765, 4638.67529272951, 4664.836730714409, 4667.88330291742, 5205.947940520584, 5334.813281535127, 5344.465553444663, 5360.356757189163, 5360.812371567015, 3938.7045971552247, 3939.409088636699, 3941.369380899071, 3942.1668923950215, 3953.090789118519, 5584.636998595119, 5609.467100625434, 5633.661980281156, 5635.774569017234, 5643.266375371176, 3945.2306800174265, 4024.4768180136393, 4041.6766658667234, 4053.250148722496, 4056.8985775355854, 4097.672186225154, 4107.301758701028, 4117.21772863155, 4123.154753275631, 4124.793760311973, 4660.953390466119, 4662.160811053975, 4668.949486365541, 4690.835820746258, 4691.835785747482, 5006.974965125224, 5217.34783152106, 5234.738263086831, 5360.544353729899, 5463.48096930791, 1333.646583655332, 1346.9865301347056, 1348.2921466230307, 1360.7482615715926, 1365.2491129878526, 911.1571796088591, 963.7735132243918, 969.3795970578835, 975.5902520941222, 979.8138353712772, 5065.797368105234, 5070.721110338966, 5085.211792172219, 5089.592832573385, 5126.179811230185, 5348.0, 5348.689571055116, 5351.732413379315, 5357.642637239602, 5362.865928351823, 4971.37857767779, 5077.740405519508, 5090.872728181751, 5093.006863661624, 5288.68267903927, 2839.6714693951176, 2877.1512403840916, 2878.3955369372247, 2878.510088773196, 2909.7526710229868, 5591.043134902751, 5618.97190514053, 5646.915296270559, 5691.690911090473, 5696.51579615729, 5396.622236443412, 5587.0, 5635.520980716685, 5772.278465191851, 5773.6431938494425, 5846.56150788684, 5887.322957859816, 5899.587028907935, 5900.468957793733, 5914.970425147847, 5472.835814925558, 5608.971955140199, 5618.606697531133, 5627.465390787868, 5635.718214089361, 5495.752691128989, 5504.073574248981, 5510.476504732044, 5513.365962914316, 5542.667439953616, 5796.246782778147, 5804.5356371491025, 5825.446582574648, 5851.64890106595, 5855.59010869235, 3933.8033098344395, 3964.0, 3984.7011474140136, 4001.8599349022643, 4048.4534587830267, 5386.703731294817, 5489.917651235155, 5576.637518561281, 5606.467385598362, 5624.184493248538, 5349.304590403222, 5357.19642053695, 5360.946390536044, 5374.811881584282, 5383.300170977748, 5468.535174510212, 5472.699001554953, 5473.562115030708, 5540.279763630702, 5548.389677135593, 5334.253204551288, 5472.781088756406, 5481.643693159932, 5491.736900512984, 5503.3395992481155, 5456.372517160577, 5492.780288788404, 5530.649473973625, 5531.585131115102, 5544.029794785977, 5276.788928442819, 5428.945710543002, 5432.076546986943, 5436.809711660231, 5449.482299181572], "value": 4174.531173251721, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.462439731195, 745.5936514599542, 778.5095389904363, 809.8906647602573, 863.3740538109871, 13540.593782186537, 14337.71324573508, 14350.354234059467, 14388.20864852434, 14442.133471991674, 13546.0, 13708.725825483509, 13724.45102195911, 13730.70463088859, 13735.381907814148, 821.7904434369242, 843.6878355008655, 846.9280111190557, 851.1190917400492, 852.5055467828645, 817.448222449844, 817.8936738224022, 819.3035919468434, 819.8647223208163, 820.4502983001404, 880.2781718990431, 881.7046289493028, 882.3206131278912, 882.571952602987, 882.5807741322884, 877.2937785083005, 878.2052242720348, 878.4509681449094, 878.8066625342415, 879.1955360844819, 639.098870592467, 640.0271587187449, 640.3340525853107, 641.1312671330327, 641.3298103481873, 15027.0, 15123.319450624638, 15136.545903622799, 15145.848541514664, 15182.316795744218, 13509.0, 13926.164430134224, 14075.788863167061, 14093.647658808612, 14320.42718291267, 11643.126765492538, 11750.471228794724, 11755.471003804763, 11811.704707382385, 11847.407629618485, 3742.3076730804614, 3758.30471362796, 3796.791176485292, 3800.1259710266795, 3911.765294082364, 50842.0, 51813.22280165838, 52676.47323526792, 60016.69991650058, 77464.0, 11508.36703981289, 11710.76578468426, 11743.47154378055, 11751.764964700658, 11857.644270671894, 21152.365429037152, 21175.78824211769, 21201.15195392167, 21339.78660213378, 21384.144634214455, 7932.405069619802, 7977.641006154737, 8021.5187088774865, 8052.315553177928, 8327.292180164633, 4385.495667998157, 4401.119776044804, 4413.58070983256, 4423.0, 4453.4433195850515, 3330.5670262866156, 3511.3152935177905, 3513.0690367052753, 3550.485179648988, 3556.9110772230383, 655.8425977765397, 666.6699983508098, 670.2024590737008, 673.0644404278057, 679.9694013769392, 623.1556241293073, 634.1375729008545, 634.3941535833268, 637.3021541412146, 638.0716058135401, 8746.0, 9395.62417503306, 9774.218062554979, 9778.336583868799, 10146.847797283046, 738.1216352540459, 756.410000199834, 763.1262204775612, 766.0888623231697, 769.9076110866732, 4636.675432719745, 4643.396358473445, 4680.976595117003, 4707.646926480527, 4728.172569800271, 77506.22493775103, 77876.22123778804, 81816.59091704503, 82476.58761706273, 82525.17474825175, 62816.0, 63056.80261565967, 69614.65192674106, 70768.0, 73710.0, 63037.36962630315, 65546.0, 66020.0, 66286.66856665783, 67021.6648916762, 8813.647454101763, 9034.86447703272, 9035.096490351176, 9035.322350823582, 9081.182693557612, 43636.0, 44464.777676111415, 45610.54389456063, 45621.0, 45949.310760337954, 53024.46975530195, 53225.0, 53985.73007134939, 54546.0, 54929.45070549403, 11237.494312755782, 11386.202965792316, 11505.884941150482, 11537.942310288396, 11580.942095289469, 12847.807282890577, 12898.613041608767, 13095.279759613313, 13233.80149297742, 13322.082692212163, 11976.58081967127, 12038.699032524257, 12264.38678066111, 12874.549390771279, 13299.335033248492, 12752.6811829705, 12882.420291087112, 13665.248411337472, 13753.243571603258, 13769.173849569059, 12670.936645316715, 12916.547920822726, 13286.468541258242, 13841.792373114613, 13851.515196968461, 10577.865813297345, 11029.83455248156, 11256.0, 11291.661250162508, 11418.086553075567, 9958.302918795904, 10574.524146413276, 10730.678079657622, 10755.892441075803, 10760.946195268974], "value": 7080.741598938727, "unit": "score"}], "name": "dromaeo_css", "value": 6947.552161588109, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 111.0, 110.0, 111.0, 110.0, 109.0, 111.0, 109.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 118.0, 121.0, 159.0, 121.0, 124.0, 118.0, 120.0, 145.0, 120.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 159.0, 159.0, 160.0, 162.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 110.0, 76.0, 77.0, 76.0, 77.0, 102.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 103.0, 82.0, 82.0, 83.0, 108.0, 83.0, 83.0, 111.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 117.0, 116.0, 116.0, 118.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 50.0, 50.0, 51.0, 51.0, 53.0, 51.0, 52.0, 54.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 68.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 80.0, 80.0, 80.0, 85.0, 79.0, 79.0, 89.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 125.0, 124.0, 129.0, 132.0, 123.0, 123.0, 121.0, 175.0, 126.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 164.0, 171.0, 171.0, 169.0, 167.0, 164.0, 167.0, 163.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 62.0, 61.0, 61.0, 67.0, 61.0, 63.0, 60.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1494.0000000000005, "alertThreshold": 2.0}]} 09:39:23 INFO - Return code: 0 09:39:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:39:23 INFO - # TBPL SUCCESS # 09:39:23 INFO - Running post-action listener: _resource_record_post_action 09:39:23 INFO - [mozharness: 2016-12-28 17:39:23.223642Z] Finished run-tests step (success) 09:39:23 INFO - Running post-run listener: _resource_record_post_run 09:39:23 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 405258240; Read time: 0; Write time: 286404 09:39:23 INFO - TinderboxPrint: CPU usage
13.1% 09:39:23 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:39:23 INFO - TinderboxPrint: I/O write bytes / time
405,258,240 / 286,404 09:39:23 INFO - TinderboxPrint: CPU idle
8,291.6 (86.9%) 09:39:23 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 09:39:23 INFO - TinderboxPrint: Swap in / out
0 / 0 09:39:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 112267264; Read time: 0; Write time: 76756 09:39:23 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 292990976; Read time: 0; Write time: 209648 09:39:23 INFO - Running post-run listener: _upload_blobber_files 09:39:23 INFO - Blob upload gear active. 09:39:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:39:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:27 INFO - (blobuploader) - INFO - Done attempting. 09:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:28 INFO - (blobuploader) - INFO - Done attempting. 09:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:29 INFO - (blobuploader) - INFO - Done attempting. 09:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:29 INFO - (blobuploader) - INFO - Done attempting. 09:39:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:29 INFO - Return code: 0 09:39:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf6e56db53ff3e1024260680ef82cf63af306c3852193a49875df4521d109fc17687af7484ae3f91bbd428a35313dca96876d2d05c9e50600d3afdb00a30011f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1045243d9e4703bbf8f5e05965e8575e913ca8e6f920c6cb8ed0731e4ee4ef689a363d85e3b383d29b4e6ee4fd6e2c3b8155b284f40d271dbc8576415331e72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79f6b7d382ec6f747b820d3fc7be36277024b235de75c4d3983bcc3802c23ebe44164d32a786884af6b478d8600f0411258dfa319d9245fb526c0aba7f89c6e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cda6eed308e6f08e6e38de4b955e89669bc93a9f4a1b4f90852ae93f0adf08ecedf8893e767c90b22d29a07d2ebc2fc352b9caa35737c4065a68537b4e4c714"} 09:39:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:29 INFO - Contents: 09:39:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf6e56db53ff3e1024260680ef82cf63af306c3852193a49875df4521d109fc17687af7484ae3f91bbd428a35313dca96876d2d05c9e50600d3afdb00a30011f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1045243d9e4703bbf8f5e05965e8575e913ca8e6f920c6cb8ed0731e4ee4ef689a363d85e3b383d29b4e6ee4fd6e2c3b8155b284f40d271dbc8576415331e72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79f6b7d382ec6f747b820d3fc7be36277024b235de75c4d3983bcc3802c23ebe44164d32a786884af6b478d8600f0411258dfa319d9245fb526c0aba7f89c6e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cda6eed308e6f08e6e38de4b955e89669bc93a9f4a1b4f90852ae93f0adf08ecedf8893e767c90b22d29a07d2ebc2fc352b9caa35737c4065a68537b4e4c714"} 09:39:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:29 INFO - Copying logs to upload dir... 09:39:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.800412 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-28 09:39:29.714290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 09:39:29.718021) ========= 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=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf6e56db53ff3e1024260680ef82cf63af306c3852193a49875df4521d109fc17687af7484ae3f91bbd428a35313dca96876d2d05c9e50600d3afdb00a30011f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1045243d9e4703bbf8f5e05965e8575e913ca8e6f920c6cb8ed0731e4ee4ef689a363d85e3b383d29b4e6ee4fd6e2c3b8155b284f40d271dbc8576415331e72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79f6b7d382ec6f747b820d3fc7be36277024b235de75c4d3983bcc3802c23ebe44164d32a786884af6b478d8600f0411258dfa319d9245fb526c0aba7f89c6e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cda6eed308e6f08e6e38de4b955e89669bc93a9f4a1b4f90852ae93f0adf08ecedf8893e767c90b22d29a07d2ebc2fc352b9caa35737c4065a68537b4e4c714"} build_url:https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011825 build_url: 'https://queue.taskcluster.net/v1/task/JbThm_WcSyqfqtf8XGNxVw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf6e56db53ff3e1024260680ef82cf63af306c3852193a49875df4521d109fc17687af7484ae3f91bbd428a35313dca96876d2d05c9e50600d3afdb00a30011f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1045243d9e4703bbf8f5e05965e8575e913ca8e6f920c6cb8ed0731e4ee4ef689a363d85e3b383d29b4e6ee4fd6e2c3b8155b284f40d271dbc8576415331e72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79f6b7d382ec6f747b820d3fc7be36277024b235de75c4d3983bcc3802c23ebe44164d32a786884af6b478d8600f0411258dfa319d9245fb526c0aba7f89c6e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cda6eed308e6f08e6e38de4b955e89669bc93a9f4a1b4f90852ae93f0adf08ecedf8893e767c90b22d29a07d2ebc2fc352b9caa35737c4065a68537b4e4c714"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 09:39:29.744207) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:39:29.744600) ========= 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=3836458cccd887f2d2de3cf1000001de-1482945528.343939-460474796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 09:39:29.829709) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 09:39:29.830057) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 09:39:29.830403) ========= ========= Total master_lag: 0.18 =========