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))