Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Running multiple fpm at the same time causes tar to error eventualy #1423

Closed
caarlos0 opened this issue Oct 19, 2017 · 14 comments
Closed

Running multiple fpm at the same time causes tar to error eventualy #1423

caarlos0 opened this issue Oct 19, 2017 · 14 comments

Comments

@caarlos0
Copy link

Hi!

Thanks for the awesome software!

I'm the author of goreleaser, which integrates with fpm.

We were running several fpm instances in parallel, meaning that one fpm instance would be packaging for linux amd64 deb and other instance might be doing the same for rpm or maybe linux x86.

Anyway, eventually, an error came out.

I added the debug flag so I could find out what was happening. Got this:

{:timestamp=>"2017-10-19T22:54:48.593577+0000", :message=>"tar: ./usr/local/bin/goreleaser: file changed as we read it", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}

https://travis-ci.org/goreleaser/goreleaser/builds/290224710#L1065

So, seems like fpm (or maybe another tool fpm uses?) is putting files in the same temp folder or something, causing tar to warn that a file changed.

More info:

For now, I disabled the concurrency in that part of the code.

Anyway, would love to see if this can be fixed here (if it belongs here).

Thanks, and let me know if I can help in any way!
@jordansissel
Copy link
Owner

jordansissel commented Oct 22, 2017 •
edited

I had a go trying to reproduce this outside of goreleaser, and this is kind of the closest I think i could come (multiple ruby running in parallel)

seq 10000 | xargs -n1 -P6 ruby -rstud/temporary -e 'puts Stud::Temporary.pathname' | tee /tmp/x

I wasn't able to cause any duplication. That said, it's certainly possible for duplication (just statistically unlikely, I think?). Your error report is very interesting!

Internal to FPM, Stud::Temporary is used to create temporary paths. This library uses SecureRandom.hex(30) to create part a random part of the pathname.

I'm not quite sure how a duplicate random-30-character string occurs unless two rubies end up getting the same random seed? This is weird. I looked at the Ruby SecureRandom code and nothing disturbing popped out.

Are you able to reproduce this on systems outside of Travis? (Hypothesis, untested: Travis maybe has a bad/low-quality entropy source?)

If we can't find the origin of this problem, I'm open to trying to work around it -- We could make Stud::Temporary calls guaranteed unique by using flock(2) or some other cross-process coordination mechanism. Alternately, fpm could include the pid in the path (simpler than flock).

A possible workaround until a solution is found:

You could give fpm a unique --workdir for each invocation. This would work around it the issue. A rough proposal perhaps to number every unit of work (0, 1, 2) and pass --workdir /tmp/fpm.{pid}/{number}/ as the workdir. This would get you one /tmp/fpm.{pid}/ per goreleaser invocation and each fpm invocation would get its own separate work directory.

@jordansissel
Copy link
Owner

jordansissel commented Oct 22, 2017 •
edited

For science, I am trying to reproduce. The travis-ci link references this:

% git checkout -qf f823ebe94b4982266d300efeb890c289e7ee021c

But this git reference doesn't exist?

⓿ localhost(~/projects/goreleaser) master
% git checkout f823ebe94b4982266d300efeb890c289e7ee021c
fatal: reference is not a tree: f823ebe94b4982266d300efeb890c289e7ee021c

@jordansissel
Copy link
Owner

I found goreleaser/goreleaser@ff42024 and am using the previous commit. I'll try to get it to fail overnight.

@jordansissel
Copy link
Owner

jordansissel commented Oct 22, 2017 •
edited

Testing with this -- and I have disabled docker and snapcraft parts of .goreleaser.yml to make it focus just on fpm.

% while PATH=$HOME/build/go/bin:$PATH go run main.go --skip-validate --skip-publish --rm-dist --debug; do sleep 1; done

Hopefully I'll be able to crash it.

@caarlos0
Copy link
Author

Hi @jordansissel!

thanks for looking into it!

I was able to reproduce the issue in my mac with this:

while true; do
  go test -v ./pipeline/fpm/... || break
done

you can test it against this tag.

anyway, the solution of appending the pid would probably be enough :D

@caarlos0
Copy link
Author

Ok, so, I tried the --workdir thing:

λ while true; do
go test -v ./pipeline/fpm/... || break
done
=== RUN   TestDescription
--- PASS: TestDescription (0.00s)
=== RUN   TestRunPipeNoFormats
--- PASS: TestRunPipeNoFormats (0.00s)
=== RUN   TestRunPipe
2017/10/22 12:04:10  info creating fpm archive      arch=i386 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest477215106/dist/mybin.rpm format=rpm workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm135694073
2017/10/22 12:04:10  info creating fpm archive      arch=amd64 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest477215106/dist/mybin.rpm format=rpm workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm198670340
2017/10/22 12:04:10  info creating fpm archive      arch=i386 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest477215106/dist/mybin.deb format=deb workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123
2017/10/22 12:04:10  info creating fpm archive      arch=amd64 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest477215106/dist/mybin.deb format=deb workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm015266774
2017/10/22 12:04:12  info new release artifact      artifact=mybin.deb
2017/10/22 12:04:17  info new release artifact      artifact=mybin.rpm
2017/10/22 12:04:17  info new release artifact      artifact=mybin.rpm
--- FAIL: TestRunPipe (6.41s)
        Error Trace:    fpm_test.go:59
	Error:		Received unexpected error Ignoring byebug-9.0.6 because its extensions are not built.  Try: gem pristine byebug --version 9.0.6
			Ignoring nokogiri-1.7.1 because its extensions are not built.  Try: gem pristine nokogiri --version 1.7.1
			Ignoring pg-0.20.0 because its extensions are not built.  Try: gem pristine pg --version 0.20.0
			Ignoring puma-3.8.2 because its extensions are not built.  Try: gem pristine puma --version 3.8.2
			Ignoring thin-1.6.2 because its extensions are not built.  Try: gem pristine thin --version 1.6.2
			{:timestamp=>"2017-10-22T12:04:11.880940-0200", :message=>"Setting workdir", :workdir=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123", :level=>:info, :file=>"fpm/command.rb", :line=>"294", :method=>"execute"}
			{:timestamp=>"2017-10-22T12:04:11.881848-0200", :message=>"Setting attribute", :output_type=>"deb", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882035-0200", :message=>"Setting attribute", :input_type=>"dir", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882210-0200", :message=>"Setting attribute", :chdir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882375-0200", :message=>"Setting attribute", :prefix=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882489-0200", :message=>"Setting attribute", :package=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest477215106/dist/mybin.deb", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882625-0200", :message=>"Setting attribute", :force?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882852-0200", :message=>"Setting attribute", :name=>"mybin", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.882984-0200", :message=>"Setting attribute", :log_level=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883105-0200", :message=>"Setting attribute", :verbose?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883210-0200", :message=>"Setting attribute", :debug?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883367-0200", :message=>"Setting attribute", :debug_workspace?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883471-0200", :message=>"Setting attribute", :version=>"1.0.0", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883615-0200", :message=>"Setting attribute", :iteration=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883748-0200", :message=>"Setting attribute", :epoch=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.883875-0200", :message=>"Setting attribute", :license=>"MIT", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884003-0200", :message=>"Setting attribute", :vendor=>"asdf", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884170-0200", :message=>"Setting attribute", :category=>"none", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884317-0200", :message=>"Setting attribute", :dependencies=>["make"], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884521-0200", :message=>"Setting attribute", :no_depends?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884688-0200", :message=>"Setting attribute", :no_auto_depends?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.884859-0200", :message=>"Setting attribute", :provides=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885031-0200", :message=>"Setting attribute", :conflicts=>["git"], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885234-0200", :message=>"Setting attribute", :replaces=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885388-0200", :message=>"Setting attribute", :config_files=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885588-0200", :message=>"Setting attribute", :directories=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885749-0200", :message=>"Setting attribute", :architecture=>"i386", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.885944-0200", :message=>"Setting attribute", :maintainer=>"me@me", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.886169-0200", :message=>"Setting attribute", :package_name_suffix=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.886394-0200", :message=>"Setting attribute", :edit?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.886557-0200", :message=>"Setting attribute", :excludes=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.886767-0200", :message=>"Setting attribute", :exclude_file=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.886907-0200", :message=>"Setting attribute", :description=>"Some description", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.887100-0200", :message=>"Setting attribute", :url=>"https://goreleaser.github.io", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.887363-0200", :message=>"Setting attribute", :inputs=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.887616-0200", :message=>"Setting attribute", :post_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.887799-0200", :message=>"Setting attribute", :pre_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.888001-0200", :message=>"Setting attribute", :post_uninstall=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.888256-0200", :message=>"Setting attribute", :pre_uninstall=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.888466-0200", :message=>"Setting attribute", :after_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.888673-0200", :message=>"Setting attribute", :before_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.888927-0200", :message=>"Setting attribute", :after_remove=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.889121-0200", :message=>"Setting attribute", :before_remove=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.889317-0200", :message=>"Setting attribute", :after_upgrade=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.889508-0200", :message=>"Setting attribute", :before_upgrade=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.889729-0200", :message=>"Setting attribute", :template_scripts?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.889916-0200", :message=>"Setting attribute", :template_value_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.890136-0200", :message=>"Setting attribute", :workdir=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.890365-0200", :message=>"Setting attribute", :source_date_epoch_from_changelog?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.890578-0200", :message=>"Setting attribute", :source_date_epoch_default=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.890756-0200", :message=>"Setting attribute", :gem_bin_path=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.891216-0200", :message=>"Setting attribute", :gem_package_prefix=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.892116-0200", :message=>"Setting attribute", :gem_package_name_prefix=>"rubygem", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.892554-0200", :message=>"Setting attribute", :gem_gem=>"gem", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.892896-0200", :message=>"Setting attribute", :gem_shebang=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.893200-0200", :message=>"Setting attribute", :gem_fix_name?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.893527-0200", :message=>"Setting attribute", :gem_fix_dependencies?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.893845-0200", :message=>"Setting attribute", :gem_env_shebang?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.894146-0200", :message=>"Setting attribute", :gem_prerelease?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.894437-0200", :message=>"Setting attribute", :gem_disable_dependencies=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.894615-0200", :message=>"Setting attribute", :gem_embed_dependencies?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.894902-0200", :message=>"Setting attribute", :gem_version_bins?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895124-0200", :message=>"Setting attribute", :gem_stagingdir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895329-0200", :message=>"Setting attribute", :deb_ignore_iteration_in_dependencies?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895553-0200", :message=>"Setting attribute", :deb_build_depends=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895704-0200", :message=>"Setting attribute", :deb_pre_depends=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895860-0200", :message=>"Setting attribute", :deb_compression=>"gz", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.895983-0200", :message=>"Setting attribute", :deb_custom_control=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896152-0200", :message=>"Setting attribute", :deb_config=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896314-0200", :message=>"Setting attribute", :deb_templates=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896455-0200", :message=>"Setting attribute", :deb_installed_size=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896646-0200", :message=>"Setting attribute", :deb_priority=>"extra", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896804-0200", :message=>"Setting attribute", :deb_use_file_permissions?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.896951-0200", :message=>"Setting attribute", :deb_user=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897112-0200", :message=>"Setting attribute", :deb_group=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897281-0200", :message=>"Setting attribute", :deb_changelog=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897435-0200", :message=>"Setting attribute", :deb_upstream_changelog=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897573-0200", :message=>"Setting attribute", :deb_recommends=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897699-0200", :message=>"Setting attribute", :deb_suggests=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897835-0200", :message=>"Setting attribute", :deb_meta_file=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.897981-0200", :message=>"Setting attribute", :deb_interest=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898148-0200", :message=>"Setting attribute", :deb_activate=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898298-0200", :message=>"Setting attribute", :deb_interest_noawait=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898427-0200", :message=>"Setting attribute", :deb_activate_noawait=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898576-0200", :message=>"Setting attribute", :deb_field=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898765-0200", :message=>"Setting attribute", :deb_no_default_config_files?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.898918-0200", :message=>"Setting attribute", :deb_auto_config_files?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.899095-0200", :message=>"Setting attribute", :deb_shlibs=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.899237-0200", :message=>"Setting attribute", :deb_init_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.899380-0200", :message=>"Setting attribute", :deb_default_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901123-0200", :message=>"Setting attribute", :deb_upstart_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901297-0200", :message=>"Setting attribute", :deb_systemd_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901450-0200", :message=>"Setting attribute", :deb_systemd_restart_after_upgrade?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901618-0200", :message=>"Setting attribute", :deb_after_purge=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901759-0200", :message=>"Setting attribute", :npm_bin=>"npm", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.901919-0200", :message=>"Setting attribute", :npm_package_name_prefix=>"node", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902048-0200", :message=>"Setting attribute", :npm_registry=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902190-0200", :message=>"Setting attribute", :rpm_use_file_permissions?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902325-0200", :message=>"Setting attribute", :rpm_user=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902445-0200", :message=>"Setting attribute", :rpm_group=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902588-0200", :message=>"Setting attribute", :rpm_defattrfile=>"-", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902707-0200", :message=>"Setting attribute", :rpm_defattrdir=>"-", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902826-0200", :message=>"Setting attribute", :rpm_rpmbuild_define=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.902942-0200", :message=>"Setting attribute", :rpm_dist=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903057-0200", :message=>"Setting attribute", :rpm_digest=>"md5", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903201-0200", :message=>"Setting attribute", :rpm_compression=>"gzip", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903318-0200", :message=>"Setting attribute", :rpm_os=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903430-0200", :message=>"Setting attribute", :rpm_changelog=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903540-0200", :message=>"Setting attribute", :rpm_summary=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903677-0200", :message=>"Setting attribute", :rpm_sign?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903802-0200", :message=>"Setting attribute", :rpm_auto_add_directories?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.903923-0200", :message=>"Setting attribute", :auto_add_exclude_directories=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904042-0200", :message=>"Setting attribute", :rpm_autoreqprov?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904181-0200", :message=>"Setting attribute", :rpm_autoreq?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904305-0200", :message=>"Setting attribute", :rpm_autoprov?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904451-0200", :message=>"Setting attribute", :attrs=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904568-0200", :message=>"Setting attribute", :rpm_init_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904686-0200", :message=>"Setting attribute", :rpm_filter_from_provides=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904828-0200", :message=>"Setting attribute", :rpm_filter_from_requires=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.904934-0200", :message=>"Setting attribute", :rpm_tag=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905044-0200", :message=>"Setting attribute", :rpm_ignore_iteration_in_dependencies?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905282-0200", :message=>"Setting attribute", :rpm_verbatim_gem_dependencies?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905412-0200", :message=>"Setting attribute", :rpm_verifyscript=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905565-0200", :message=>"Setting attribute", :rpm_pretrans=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905682-0200", :message=>"Setting attribute", :rpm_posttrans=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905811-0200", :message=>"Setting attribute", :rpm_trigger_before_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.905988-0200", :message=>"Setting attribute", :rpm_trigger_after_install=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.906126-0200", :message=>"Setting attribute", :rpm_trigger_before_uninstall=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.906268-0200", :message=>"Setting attribute", :rpm_trigger_after_target_uninstall=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.906470-0200", :message=>"Setting attribute", :cpan_perl_bin=>"perl", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.906638-0200", :message=>"Setting attribute", :cpan_cpanm_bin=>"cpanm", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.906856-0200", :message=>"Setting attribute", :cpan_mirror=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.908883-0200", :message=>"Setting attribute", :cpan_mirror_only?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.910349-0200", :message=>"Setting attribute", :cpan_package_name_prefix=>"perl", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.910565-0200", :message=>"Setting attribute", :cpan_test?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.910753-0200", :message=>"Setting attribute", :cpan_perl_lib_path=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.910963-0200", :message=>"Setting attribute", :cpan_sandbox_non_core?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.911147-0200", :message=>"Setting attribute", :cpan_cpanm_force?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.911383-0200", :message=>"Setting attribute", :pear_package_name_prefix=>"php-pear", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.911577-0200", :message=>"Setting attribute", :pear_channel=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.911739-0200", :message=>"Setting attribute", :pear_channel_update?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.911874-0200", :message=>"Setting attribute", :pear_bin_dir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912038-0200", :message=>"Setting attribute", :pear_php_bin=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912131-0200", :message=>"Setting attribute", :pear_php_dir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912216-0200", :message=>"Setting attribute", :pear_data_dir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912426-0200", :message=>"Setting attribute", :python_bin=>"python", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912597-0200", :message=>"Setting attribute", :python_easyinstall=>"easy_install", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912762-0200", :message=>"Setting attribute", :python_pip=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.912920-0200", :message=>"Setting attribute", :python_pypi=>"https://pypi.python.org/simple", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913109-0200", :message=>"Setting attribute", :python_package_prefix=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913260-0200", :message=>"Setting attribute", :python_package_name_prefix=>"python", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913402-0200", :message=>"Setting attribute", :python_fix_name?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913541-0200", :message=>"Setting attribute", :python_fix_dependencies?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913700-0200", :message=>"Setting attribute", :python_downcase_name?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.913907-0200", :message=>"Setting attribute", :python_downcase_dependencies?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.914236-0200", :message=>"Setting attribute", :python_install_bin=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.914388-0200", :message=>"Setting attribute", :python_install_lib=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.914729-0200", :message=>"Setting attribute", :python_install_data=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.914869-0200", :message=>"Setting attribute", :python_dependencies?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.915011-0200", :message=>"Setting attribute", :python_obey_requirements_txt?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.915224-0200", :message=>"Setting attribute", :python_scripts_executable=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.915444-0200", :message=>"Setting attribute", :python_disable_dependency=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.915647-0200", :message=>"Setting attribute", :python_setup_py_arguments=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.915889-0200", :message=>"Setting attribute", :osxpkg_identifier_prefix=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916106-0200", :message=>"Setting attribute", :osxpkg_payload_free?=>false, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916279-0200", :message=>"Setting attribute", :osxpkg_ownership=>"recommended", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916434-0200", :message=>"Setting attribute", :osxpkg_postinstall_action=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916610-0200", :message=>"Setting attribute", :osxpkg_dont_obsolete=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916778-0200", :message=>"Setting attribute", :solaris_user=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.916945-0200", :message=>"Setting attribute", :solaris_group=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917062-0200", :message=>"Setting attribute", :p5p_user=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917183-0200", :message=>"Setting attribute", :p5p_group=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917301-0200", :message=>"Setting attribute", :p5p_zonetype=>"value=global value=nonglobal", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917448-0200", :message=>"Setting attribute", :p5p_publisher=>"FPM", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917763-0200", :message=>"Setting attribute", :p5p_lint?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.917922-0200", :message=>"Setting attribute", :p5p_validate?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918067-0200", :message=>"Setting attribute", :freebsd_origin=>"fpm/<name>", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918219-0200", :message=>"Setting attribute", :virtualenv_pypi=>"https://pypi.python.org/simple", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918353-0200", :message=>"Setting attribute", :virtualenv_package_name_prefix=>"virtualenv", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918484-0200", :message=>"Setting attribute", :virtualenv_install_location=>"/usr/share/python", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918645-0200", :message=>"Setting attribute", :virtualenv_fix_name?=>true, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918839-0200", :message=>"Setting attribute", :virtualenv_other_files_dir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.918996-0200", :message=>"Setting attribute", :virtualenv_pypi_extra_index_urls=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.919155-0200", :message=>"Setting attribute", :virtualenv_setup_install?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.919363-0200", :message=>"Setting attribute", :virtualenv_system_site_packages?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.919527-0200", :message=>"Setting attribute", :virtualenv_find_links_urls=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.919723-0200", :message=>"Setting attribute", :pleaserun_name=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.919877-0200", :message=>"Setting attribute", :pleaserun_chdir=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.920062-0200", :message=>"Setting attribute", :pacman_optional_depends_list=>[], :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.920210-0200", :message=>"Setting attribute", :pacman_use_file_permissions?=>nil, :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.920416-0200", :message=>"Setting attribute", :pacman_user=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.920538-0200", :message=>"Setting attribute", :pacman_group=>"root", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.920642-0200", :message=>"Setting attribute", :pacman_compression=>"xz", :level=>:debug, :file=>"fpm/command.rb", :line=>"338", :method=>"block (2 levels) in execute"}
			{:timestamp=>"2017-10-22T12:04:11.921233-0200", :message=>"Cloning path", :source=>"mybin", :destination=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-dir-staging-15325914bf56c3a9dd9aebc6fe1e31f61ad2d355e9ff435f6aaca97a3863/usr/local/bin/mybin", :level=>:debug, :file=>"fpm/package/dir.rb", :line=>"122", :method=>"clone", "method"=>"input"}
			{:timestamp=>"2017-10-22T12:04:11.921575-0200", :message=>"Copying path", :source=>"mybin", :destination=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-dir-staging-15325914bf56c3a9dd9aebc6fe1e31f61ad2d355e9ff435f6aaca97a3863/usr/local/bin/mybin", :level=>:debug, :file=>"fpm/package/dir.rb", :line=>"167", :method=>"copy", "method"=>"input"}
			{:timestamp=>"2017-10-22T12:04:11.922592-0200", :message=>"Linking", :source=>"mybin", :destination=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-dir-staging-15325914bf56c3a9dd9aebc6fe1e31f61ad2d355e9ff435f6aaca97a3863/usr/local/bin/mybin", :level=>:debug, :file=>"fpm/package/dir.rb", :line=>"200", :method=>"copy", "method"=>"input"}
			{:timestamp=>"2017-10-22T12:04:11.923362-0200", :message=>"Setting from flags: architecture=i386", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.923550-0200", :message=>"Setting from flags: description=Some description", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.923719-0200", :message=>"Setting from flags: epoch=", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.923873-0200", :message=>"Setting from flags: iteration=", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924023-0200", :message=>"Setting from flags: license=MIT", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924201-0200", :message=>"Setting from flags: maintainer=me@me", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924331-0200", :message=>"Setting from flags: name=mybin", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924509-0200", :message=>"Setting from flags: url=https://goreleaser.github.io", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924668-0200", :message=>"Setting from flags: vendor=asdf", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.924972-0200", :message=>"Setting from flags: version=1.0.0", :level=>:info, :file=>"fpm/command.rb", :line=>"398", :method=>"block in execute"}
			{:timestamp=>"2017-10-22T12:04:11.925189-0200", :message=>"Converting dir to deb", :level=>:info, :file=>"fpm/package.rb", :line=>"191", :method=>"convert"}
			{:timestamp=>"2017-10-22T12:04:11.926251-0200", :message=>"Cleaning up staging path", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-staging-6a63d207ea6fd413086bc31d0cb621348b5eb97288d838b40bd810cf19f5", :level=>:debug, :file=>"fpm/package.rb", :line=>"280", :method=>"cleanup_staging"}
			{:timestamp=>"2017-10-22T12:04:11.928594-0200", :message=>"Creating boilerplate changelog file", :level=>:info, :file=>"fpm/package/deb.rb", :line=>"476", :method=>"block (2 levels) in output"}
			{:timestamp=>"2017-10-22T12:04:11.928837-0200", :message=>"Reading template", :path=>"/usr/local/lib/ruby/gems/2.4.0/gems/fpm-1.9.3/templates/deb/changelog.erb", :level=>:info, :file=>"fpm/package.rb", :line=>"329", :method=>"template"}
			{:timestamp=>"2017-10-22T12:04:11.930137-0200", :message=>"No deb_installed_size set, calculating now.", :level=>:info, :file=>"fpm/package/deb.rb", :line=>"748", :method=>"write_control"}
			{:timestamp=>"2017-10-22T12:04:11.931121-0200", :message=>"Using 'deb.erb' template for the control file", :level=>:debug, :file=>"fpm/package/deb.rb", :line=>"767", :method=>"block in write_control"}
			{:timestamp=>"2017-10-22T12:04:11.931355-0200", :message=>"Reading template", :path=>"/usr/local/lib/ruby/gems/2.4.0/gems/fpm-1.9.3/templates/deb.erb", :level=>:info, :file=>"fpm/package.rb", :line=>"329", :method=>"template"}
			{:timestamp=>"2017-10-22T12:04:11.933481-0200", :message=>"Writing control file", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control/control", :level=>:debug, :file=>"fpm/package/deb.rb", :line=>"771", :method=>"block in write_control"}
			{:timestamp=>"2017-10-22T12:04:11.934039-0200", :message=>"Debian packaging tools generally labels all files in /etc as config files, as mandated by policy, so fpm defaults to this behavior for deb packages. You can disable this default behavior with --deb-no-default-config-files flag", :level=>:warn, :file=>"fpm/package/deb.rb", :line=>"816", :method=>"write_conffiles"}
			{:timestamp=>"2017-10-22T12:04:11.939657-0200", :message=>"Creating", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control.tar.gz", :from=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control", :level=>:info, :file=>"fpm/package/deb.rb", :line=>"724", :method=>"block in write_control_tarball"}
			{:timestamp=>"2017-10-22T12:04:12.015627-0200", :message=>"Running command", :args=>["tar", "-C", "/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control", "-zcf", "/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control.tar.gz", "--owner=0", "--group=0", "--numeric-owner", "."], :level=>:debug, :file=>"fpm/util.rb", :line=>"145", :method=>"execmd"}
			{:timestamp=>"2017-10-22T12:04:12.019128-0200", :message=>"Process is running", :pid=>8159, :level=>:debug, :file=>"fpm/util.rb", :line=>"163", :method=>"execmd"}
			{:timestamp=>"2017-10-22T12:04:12.021527-0200", :message=>"tar: Option --owner=0 is not supported", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.021898-0200", :message=>"Usage:", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.022297-0200", :message=>"  List:    tar -tf <archive-filename>", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.022522-0200", :message=>"  Extract: tar -xf <archive-filename>", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.022737-0200", :message=>"  Create:  tar -cf <archive-filename> [filenames...]", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.022973-0200", :message=>"  Help:    tar --help", :level=>:info, :file=>"cabin/mixins/pipe.rb", :line=>"47", :method=>"block in pipe"}
			{:timestamp=>"2017-10-22T12:04:12.024334-0200", :message=>"Process failed: tar failed (exit code 1). Full command was:[\"tar\", \"-C\", \"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control\", \"-zcf\", \"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644/control.tar.gz\", \"--owner=0\", \"--group=0\", \"--numeric-owner\", \".\"]", :level=>:error, :file=>"fpm/command.rb", :line=>"505", :method=>"rescue in execute"}
			{:timestamp=>"2017-10-22T12:04:12.024581-0200", :message=>"Cleaning up staging path", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-dir-staging-15325914bf56c3a9dd9aebc6fe1e31f61ad2d355e9ff435f6aaca97a3863", :level=>:debug, :file=>"fpm/package.rb", :line=>"280", :method=>"cleanup_staging"}
			{:timestamp=>"2017-10-22T12:04:12.026288-0200", :message=>"Cleaning up build path", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-dir-build-b7fa1fffd855ef7fa6e423fdd6d5bd36072683efa095af8ac47380c1382d", :level=>:debug, :file=>"fpm/package.rb", :line=>"287", :method=>"cleanup_build"}
			{:timestamp=>"2017-10-22T12:04:12.026932-0200", :message=>"Cleaning up build path", :path=>"/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm128671123/package-deb-build-b6a6bbb85453698289c3c8ee68ed779c7d6728ed46ab4c88bf195f25b644", :level=>:debug, :file=>"fpm/package.rb", :line=>"287", :method=>"cleanup_build"}

=== RUN   TestNoFPMInPath
--- PASS: TestNoFPMInPath (0.00s)
=== RUN   TestCreateFileDoesntExist
2017/10/22 12:04:17  info creating fpm archive      arch=amd64 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest288407357/dist/mybin.rpm format=rpm workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm098773368
2017/10/22 12:04:17  info creating fpm archive      arch=amd64 file=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/archivetest288407357/dist/mybin.deb format=deb workdir=/var/folders/6y/p5lltz4566ngynrc2wkjz_9w0000gp/T/fpm075184759
--- PASS: TestCreateFileDoesntExist (0.81s)
=== RUN   TestRunPipeWithExtraFiles
--- PASS: TestRunPipeWithExtraFiles (0.00s)
FAIL
exit status 1
FAIL	github.com/goreleaser/goreleaser/pipeline/fpm	7.238s

@caarlos0
Copy link
Author

Different error now: tar: Option --owner=0 is not supported

@caarlos0
Copy link
Author

Found issue #882, tried:

$ PATH="/usr/local/opt/gnu-tar/libexec/gnubin:$PATH"
$ while true; do
  go test -v ./pipeline/fpm/... || break
done

Running for a couple of minutes now...

caarlos0 added a commit to goreleaser/goreleaser that referenced this issue Oct 22, 2017
@caarlos0
fix: a new workdir for each fpm instance
55af955 
this may avoid running into jordansissel/fpm#1423
caarlos0 added a commit to goreleaser/goreleaser that referenced this issue Oct 22, 2017
@caarlos0
fix: a new workdir for each fpm instance
85eff53 
This may avoid running into
jordansissel/fpm#1423
@caarlos0
Copy link
Author

Yeah, seems like giving a workdir for each instance fixes it.

caarlos0 added a commit to goreleaser/goreleaser that referenced this issue Oct 23, 2017
@caarlos0
fix: a new workdir for each fpm instance
11fee22 
This may avoid running into
jordansissel/fpm#1423
@jordansissel
Copy link
Owner

This is still on my backlog to fix -- something to automatically make workdir safer to use and have fpm generate some unique path with it {workdir}/{uniquerandom}/... where fpm will do work.

@caarlos0
Copy link
Author

caarlos0 commented Feb 8, 2018

@jordansissel no need to hurry :D

I was able to workaround the issue with goreleaser/goreleaser@11fee22

thanks!

@ravigh
Copy link

ravigh commented May 28, 2018

I ended up with same error.

Process failed: tar failed (exit code 1). Full command was:["tar", "-C", "/var/folders/6l/tp9krwld3rz0rb7tbqg417300000gy/T/package-deb-build-d95fd5fcfd6eba58ae7e7735927add5a3c672ae96af4e5f1041ea46ae754/control", "-zcf", "/var/folders/6l/tp9krwld3rz0rb7tbqg417300000gy/T/package-deb-build-d95fd5fcfd6eba58ae7e7735927add5a3c672ae96af4e5f1041ea46ae754/control.tar.gz", "--owner=0", "--group=0", "--numeric-owner", "."] {:level=>:error, :file=>"fpm/command.rb", :line=>"505", :method=>"rescue in execute"}

i am an Admin , can this be fixed using some parameters.

@fizerkhan
Copy link

+1 I do have the same error. How do I fix it?

@icemioi
Copy link

icemioi commented Sep 2, 2020

+1 I do have the same error.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
5 participants
@jordansissel @ravigh @caarlos0 @fizerkhan @icemioi