leap_cli should output line by line so timesptamping works
It seems that until the puppetrun, it works, but using 'ts', it stalls after 'applying puppet', and puts out all puppet messages at once:
--- git/bitmask ‹master› » leap deploy | ts -i "%M:%.S" 00:05.213905 = checking node 00:00.000150 - [quetzal.jessie.bitmask.net] ok 00:00.000026 = synching configuration files 00:00.000019 - hiera/quetzal.yaml -> quetzal:/etc/leap/hiera.yaml 00:01.666611 - webapp/, files/puppet/, files/puppet/modules/ -> quetzal:/srv/leap/files 00:01.688988 = synching puppet manifests 00:00.000209 - /home/varac/dev/projects/leap/git/leap_platform/[bin,tests,puppet] -> quetzal.jessie.bitmask.net:/srv/leap 00:03.017471 = applying puppet 05:25.721976 - [quetzal.jessie.bitmask.net] STARTING APPLY {user: varac, platform: 0.8 (develop bc073), leap_cli: 1.8 (develop cc63a)} 00:00.000109 - [quetzal.jessie.bitmask.net] Notice: Scope(Class[Site_config::Packages::Base]): jessie 00:00.000031 - [quetzal.jessie.bitmask.net] Notice: Scope(Class[main]): Services for quetzal.jessie.bitmask.net: couchdb, mx, pixelated, soledad, webapp 00:00.000029 - [quetzal.jessie.bitmask.net] (at /usr/lib/ruby/vendor_ruby/puppet/parser/templatewrapper.rb:76:in `method_missing') 00:00.000038 - [quetzal.jessie.bitmask.net] Notice: Compiled catalog for quetzal.jessie.bitmask.net in environment production in 6.11 seconds 00:00.000030 - [quetzal.jessie.bitmask.net] Warning: Found multiple default providers for vcsrepo: bzr, cvs, dummy, git, hg, svn; using bzr 00:00.000029 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_sshd::Deploy_authorized_keys/Site_sshd::Authorized_keys[root]/File[/root/.ssh/authorized_keys]/content: content changed '{md5}95002092c2e3da48edd44eabdd44a78c' to '{md5}299d20b0a62eb8bca92c45303a5e36d6' 00:00.000029 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Couchdb::Base/File[/etc/couchdb/local.d/admin.ini]/content: content changed '{md5}59117ffe032d6d87e400d4bc93a80f85' to '{md5}4e6d9065d4b2a5a84d33848cce9841f4' 00:00.000031 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Couchdb::Base/Exec[couchdb_restart]: Triggered 'refresh' from 1 events 00:00.000029 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Rubygems/Package[rubygems]/ensure: ensure changed 'purged' to 'present' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/views/users]/ensure: created 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp::Couchdb/File[/srv/leap/webapp/config/couchdb.admin.yml]/owner: owner changed 'leap-webapp' to 'root' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp::Couchdb/File[/srv/leap/webapp/config/couchdb.admin.yml]/group: group changed 'leap-webapp' to 'root' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/views/common]/ensure: created 00:00.000027 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/views/common/_download_button.html.haml]/ensure: defined content as '{md5}d41d8cd98f00b204e9800998ecf8427e' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/views/users/show.html.haml]/ensure: defined content as '{md5}8934a49717e4c0af63ec7c3ed97caa93' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/locales/en.yml]/content: content changed '{md5}d5a9f757558a4c98ae29cdad32904f9a' to '{md5}732512c91974b9f5541c2d9c5522ed08' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Pixelated::Dispatcher/File[/srv/leap/webapp/config/customization/locales/en.yml]/mode: mode changed '0644' to '0600' 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp/Try::File[/srv/leap/webapp/config/customization]/Exec[chgrp_/srv/leap/webapp/config/customization]: Triggered 'refresh' from 1 events 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp/Try::File[/srv/leap/webapp/config/customization]/Exec[chmod_/srv/leap/webapp/config/customization]: Triggered 'refresh' from 1 events 00:00.000027 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp/Try::File[/srv/leap/webapp/config/customization]/Exec[chown_/srv/leap/webapp/config/customization]: Triggered 'refresh' from 1 events 00:00.000028 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Couchdb::Base/Service[couchdb]: Triggered 'refresh' from 1 events 00:00.000027 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Site_webapp/Exec[compile_assets]: Triggered 'refresh' from 1 events 00:00.000027 - [quetzal.jessie.bitmask.net] Notice: /Stage[main]/Apache::Base/Service[apache]: Triggered 'refresh' from 2 events 00:00.000027 - [quetzal.jessie.bitmask.net] Notice: Finished catalog run in 308.90 seconds 00:00.000027 - [quetzal.jessie.bitmask.net] APPLY COMPLETE (changes made) {user: varac, platform: 0.8 (develop bc073), leap_cli: 1.8 (develop cc63a)}
I would need this because i'd like to debug what is taking so long (no changes, 5mins deploy time :/)
(from redmine: created on 2016-01-13, relates #5442 (closed), relates #7797 (closed))